Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250604220005\orcl_j005_14216_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:36946M/63366M, Ph+PgF:43664M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 31
Windows thread id: 14216, image: ORACLE.EXE (J005)


*** 2025-06-04 22:00:05.613
*** SESSION ID:(249.933) 2025-06-04 22:00:05.613
 

*** 2025-06-04 22:00:05.613
Process diagnostic dump for ORACLE.EXE (J005), OS id=14216,
pid: 31, proc_ser: 198, sid: 249, sess_ser: 933 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14216
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.001108 sec, exc=0.001108 sec, total=0.001108 sec
    wait times: max=0.500000 sec, heur=4.521838 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.000012 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.510088 sec, exc=0.510088 sec, total=0.510088 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.510110 sec, exc=0.510110 sec, total=0.510110 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 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.000009 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.500463 sec, exc=0.500463 sec, total=0.500463 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 249 serial 933
---------------------------------------------------
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
---------------------------------------------------
  [5 samples,                                              22:00:01 - 22:00:05]
    idle wait at each sample
  [session created at: 22:00:01]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-04 22:00:05.613
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 31 (osid: 14216, J005)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-31 06:00:02.073734 :9E511EDC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12592 pso_num=31 pso_serial#=177
2025-05-31 06:00:02.073858 :9E511EDD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12592 osp_idx=29 osp_ver=431 osp_pg=0 (spawn #236924)
2025-05-31 06:00:02.073859 :9E511EDE:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 06:00:02.073860 :9E511EDF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=140 time=1646960704
2025-05-31 06:00:02.073860 :9E511EE0:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 06:00:02.073861 :9E511EE1:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 06:00:02.073861 :9E511EE2:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 06:00:02.073862 :9E511EE3:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 06:00:02.073862 :9E511EE4:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 06:00:02.074435 :9E511EF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-31 06:00:02.181121 :9E512156:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-05-31 06:00:32.280293 :9E5131C2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-31 14:03:57.054426 :9E76DE33:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-31 14:03:57.044
2025-05-31 14:03:57.054427 :9E76DE34:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15324 pso_num=31 pso_serial#=178
2025-05-31 14:03:57.054572 :9E76DE35:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=15324 osp_idx=29 osp_ver=432 osp_pg=0 (spawn #238054)
2025-05-31 14:03:57.054573 :9E76DE36:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 14:03:57.054573 :9E76DE37:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=141 time=1675995704
2025-05-31 14:03:57.054574 :9E76DE38:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 14:03:57.054574 :9E76DE39:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 14:03:57.054575 :9E76DE3A:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 14:03:57.054575 :9E76DE3B:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 14:03:57.054575 :9E76DE3C:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 14:03:57.055083 :9E76DE40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-31 14:03:57.055122 :9E76DE46:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-31 14:03:57.064314 :9E76DE58:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-31 18:04:29.324306 :9E897AA3:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-31 18:04:29.316
2025-05-31 18:04:29.324308 :9E897AA4:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=3624 pso_num=31 pso_serial#=179
2025-05-31 18:04:29.324457 :9E897AA5:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=3624 osp_idx=29 osp_ver=433 osp_pg=0 (spawn #238611)
2025-05-31 18:04:29.324457 :9E897AA6:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 18:04:29.324458 :9E897AA7:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=142 time=1690427969
2025-05-31 18:04:29.324459 :9E897AA8:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 18:04:29.324459 :9E897AA9:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 18:04:29.324459 :9E897AAA:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 18:04:29.324460 :9E897AAB:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 18:04:29.324461 :9E897AAC:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 18:04:29.325016 :9E897AAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-31 18:04:29.325066 :9E897AB3:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-31 18:04:29.333761 :9E897AC8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-31 23:59:09.945041 :9EA4DD30:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-31 23:59:09.932
2025-05-31 23:59:09.945043 :9EA4DD31:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4856 pso_num=31 pso_serial#=180
2025-05-31 23:59:09.945181 :9EA4DD32:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=4856 (legacy spawn)
2025-05-31 23:59:09.959066 :9EA4DD33:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-31 23:59:13.231696 :9EA4DE3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-31 23:59:13.231872 :9EA4DE40:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-05-31 23:59:13.232952 :9EA4DE44:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-31 23:59:13.483342 :9EA4DE9E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-31 23:59:13.483
2025-05-31 23:59:13.483344 :9EA4DE9F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12668 pso_num=31 pso_serial#=181
2025-05-31 23:59:13.483497 :9EA4DEA0:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12668 (legacy spawn)
2025-05-31 23:59:13.498611 :9EA4DEA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-31 23:59:46.618055 :9EA4EAB7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-31 23:59:46.695320 :9EA4EABA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-31 23:59:46.693
2025-05-31 23:59:46.695322 :9EA4EABB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10280 pso_num=31 pso_serial#=182
2025-05-31 23:59:46.695462 :9EA4EABC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10280 (legacy spawn)
2025-05-31 23:59:46.710091 :9EA4EABD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-01 00:00:19.874840 :9EA4F7CA:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-01 06:00:02.086429 :9EC0A22D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-01 06:00:02.084
2025-06-01 06:00:02.086430 :9EC0A22E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14344 pso_num=31 pso_serial#=183
2025-06-01 06:00:02.086560 :9EC0A22F:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=14344 osp_idx=29 osp_ver=437 osp_pg=0 (spawn #240269)
2025-06-01 06:00:02.086561 :9EC0A230:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-01 06:00:02.086561 :9EC0A231:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=143 time=1733360719
2025-06-01 06:00:02.086562 :9EC0A232:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-06-01 06:00:02.086562 :9EC0A233:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-06-01 06:00:02.086563 :9EC0A234:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-06-01 06:00:02.086563 :9EC0A235:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-01 06:00:02.086563 :9EC0A236:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-01 06:00:02.087070 :9EC0A237:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-01 06:00:32.152465 :9EC0B6E8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-01 23:59:11.898520 :9F14CE91:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-01 23:59:11.887
2025-06-01 23:59:11.898522 :9F14CE92:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9008 pso_num=31 pso_serial#=184
2025-06-01 23:59:11.898650 :9F14CE93:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=9008 osp_idx=29 osp_ver=438 osp_pg=0 (spawn #242765)
2025-06-01 23:59:11.898651 :9F14CE94:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-01 23:59:11.898651 :9F14CE95:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=144 time=1798110532
2025-06-01 23:59:11.898651 :9F14CE96:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-06-01 23:59:11.898652 :9F14CE97:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-06-01 23:59:11.898652 :9F14CE98:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-06-01 23:59:11.898652 :9F14CE99:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-01 23:59:11.898653 :9F14CE9A:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-01 23:59:11.899123 :9F14CE9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-01 23:59:41.930054 :9F14D926:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-01 23:59:54.426259 :9F14DD82:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-01 23:59:54.426
2025-06-01 23:59:54.426261 :9F14DD83:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14196 pso_num=31 pso_serial#=185
2025-06-01 23:59:54.426413 :9F14DD84:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14196 (legacy spawn)
2025-06-01 23:59:54.441115 :9F14DD85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-01 23:59:57.888991 :9F14DE8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-01 23:59:57.889174 :9F14DE90:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-01 23:59:57.890477 :9F14DE95:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-01 23:59:58.145010 :9F14DECE:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-01 23:59:58.133
2025-06-01 23:59:58.145012 :9F14DECF:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13176 pso_num=31 pso_serial#=186
2025-06-01 23:59:58.145151 :9F14DED0:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13176 (legacy spawn)
2025-06-01 23:59:58.159424 :9F14DED1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 00:06:18.115691 :9F156709:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 00:06:18.115866 :9F15670E:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-02 00:06:18.117142 :9F156713:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-02 22:00:02.084946 :9F7B33D6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-02 22:00:02.077
2025-06-02 22:00:02.084947 :9F7B33D7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14084 pso_num=31 pso_serial#=187
2025-06-02 22:00:02.085075 :9F7B33D8:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=14084 osp_idx=29 osp_ver=441 osp_pg=0 (spawn #245830)
2025-06-02 22:00:02.085075 :9F7B33D9:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-02 22:00:02.085077 :9F7B33DA:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=145 time=1877360735
2025-06-02 22:00:02.085077 :9F7B33DB:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-06-02 22:00:02.085078 :9F7B33DC:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-06-02 22:00:02.085078 :9F7B33DD:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-06-02 22:00:02.085078 :9F7B33DE:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-02 22:00:02.085079 :9F7B33DF:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-02 22:00:02.085646 :9F7B33E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-02 22:00:32.173696 :9F7B4866:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-02 23:59:18.220447 :9F849122:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-02 23:59:18.218
2025-06-02 23:59:18.220449 :9F849123:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12936 pso_num=31 pso_serial#=188
2025-06-02 23:59:18.220585 :9F849124:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12936 (legacy spawn)
2025-06-02 23:59:18.234468 :9F849125:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 23:59:21.427851 :9F84922B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 23:59:21.428041 :9F849230:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-02 23:59:21.429126 :9F849235:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-02 23:59:21.688262 :9F84924B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-02 23:59:21.676
2025-06-02 23:59:21.688265 :9F84924C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14924 pso_num=31 pso_serial#=189
2025-06-02 23:59:21.688407 :9F84924D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14924 (legacy spawn)
2025-06-02 23:59:21.702673 :9F84924E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 23:59:54.842467 :9F849F2E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-02 23:59:55.051298 :9F849F5F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-02 23:59:55.041
2025-06-02 23:59:55.051300 :9F849F60:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14952 pso_num=31 pso_serial#=190
2025-06-02 23:59:55.051435 :9F849F61:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14952 (legacy spawn)
2025-06-02 23:59:55.065492 :9F849F62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 23:59:58.225137 :9F84A04C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-02 23:59:58.225312 :9F84A051:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-02 23:59:58.226625 :9F84A056:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-02 23:59:58.476346 :9F84A059:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-02 23:59:58.476
2025-06-02 23:59:58.476349 :9F84A05A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16244 pso_num=31 pso_serial#=191
2025-06-02 23:59:58.476506 :9F84A05B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16244 (legacy spawn)
2025-06-02 23:59:58.490620 :9F84A05C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 00:06:18.426514 :9F852782:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 00:06:18.426689 :9F852787:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-03 00:06:18.427975 :9F85278C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-03 22:00:02.057313 :9FEC81E8:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-03 22:00:02.054
2025-06-03 22:00:02.057314 :9FEC81E9:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12820 pso_num=31 pso_serial#=192
2025-06-03 22:00:02.057433 :9FEC81EA:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12820 osp_idx=29 osp_ver=446 osp_pg=0 (spawn #249159)
2025-06-03 22:00:02.057433 :9FEC81EB:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-03 22:00:02.057433 :9FEC81EC:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=146 time=1963760688
2025-06-03 22:00:02.057434 :9FEC81ED:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-06-03 22:00:02.057434 :9FEC81EE:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-06-03 22:00:02.057435 :9FEC81EF:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-06-03 22:00:02.057435 :9FEC81F0:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-03 22:00:02.057436 :9FEC81F1:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-03 22:00:02.057932 :9FEC8203:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-03 22:00:32.146055 :9FEC9697:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-03 23:59:18.122016 :9FF5F235:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-03 23:59:18.111
2025-06-03 23:59:18.122018 :9FF5F236:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12532 pso_num=31 pso_serial#=193
2025-06-03 23:59:18.122308 :9FF5F237:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12532 (legacy spawn)
2025-06-03 23:59:18.138034 :9FF5F238:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 23:59:21.438058 :9FF5F395:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 23:59:21.438089 :9FF5F398:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 23:59:21.438246 :9FF5F39B:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-03 23:59:21.439634 :9FF5F3AC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-03 23:59:21.691358 :9FF5F3DE:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-03 23:59:21.676
2025-06-03 23:59:21.691361 :9FF5F3DF:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14240 pso_num=31 pso_serial#=194
2025-06-03 23:59:21.691509 :9FF5F3E0:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14240 (legacy spawn)
2025-06-03 23:59:21.705861 :9FF5F3E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 23:59:54.892222 :9FF6006C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-03 23:59:56.044871 :9FF6007B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-03 23:59:56.029
2025-06-03 23:59:56.044873 :9FF6007C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13808 pso_num=31 pso_serial#=195
2025-06-03 23:59:56.045007 :9FF6007D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13808 (legacy spawn)
2025-06-03 23:59:56.051486 :9FF6007E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 23:59:56.051671 :9FF60083:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-03 23:59:56.052475 :9FF60088:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-03 23:59:56.069165 :9FF6008B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-03 23:59:56.061
2025-06-03 23:59:56.069167 :9FF6008C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14760 pso_num=31 pso_serial#=196
2025-06-03 23:59:56.069309 :9FF6008D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14760 (legacy spawn)
2025-06-03 23:59:56.075770 :9FF6008E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-03 23:59:56.075940 :9FF60097:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-03 23:59:56.076829 :9FF60098:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-03 23:59:58.541332 :9FF60195:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-03 23:59:58.530
2025-06-03 23:59:58.541333 :9FF60196:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10948 pso_num=31 pso_serial#=197
2025-06-03 23:59:58.541475 :9FF60197:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10948 (legacy spawn)
2025-06-03 23:59:58.555894 :9FF601A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-04 00:06:18.513029 :9FF68A75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-04 00:06:18.513212 :9FF68A7A:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-06-04 00:06:18.514665 :9FF68A7F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-06-04 22:00:01.091511 :A05CB9B0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 06-04 22:00:01.082
2025-06-04 22:00:01.091512 :A05CB9B1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14216 pso_num=31 pso_serial#=198
2025-06-04 22:00:01.091644 :A05CB9B2:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=14216 osp_idx=29 osp_ver=452 osp_pg=0 (spawn #252490)
2025-06-04 22:00:01.091645 :A05CB9B3:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-04 22:00:01.091645 :A05CB9B4:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=147 time=2050159735
2025-06-04 22:00:01.091646 :A05CB9B5:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-06-04 22:00:01.091646 :A05CB9B6:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-06-04 22:00:01.091647 :A05CB9B7:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-06-04 22:00:01.091647 :A05CB9B8:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-04 22:00:01.091647 :A05CB9B9:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-04 22:00:01.092200 :A05CB9BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 31 (osid: 14216, J005)