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


*** 2025-05-11 06:00:06.772
*** SESSION ID:(249.645) 2025-05-11 06:00:06.772
 

*** 2025-05-11 06:00:06.772
Process diagnostic dump for ORACLE.EXE (J005), OS id=12740,
pid: 31, proc_ser: 59, sid: 249, sess_ser: 645 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12740
  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.206513 sec, exc=0.206513 sec, total=0.206513 sec
    wait times: max=0.500000 sec, heur=4.698818 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.500003 sec, exc=0.500003 sec, total=0.500003 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 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.499988 sec, exc=0.499988 sec, total=0.499988 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000014 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.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.000008 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.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
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.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.500237 sec, exc=0.500237 sec, total=0.500237 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.499988 sec, exc=0.499988 sec, total=0.499988 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 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.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.000013 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.491997 sec, exc=0.491997 sec, total=0.491997 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 645
---------------------------------------------------
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,                                              06:00:02 - 06:00:06]
    idle wait at each sample
  [session created at: 06:00:02]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-11 06:00:06.772
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 31 (osid: 12740, J005)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-07 23:59:43.677955 :941B815E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-07 23:59:54.846601 :941B854B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 23:59:54.837
2025-05-07 23:59:54.846603 :941B854C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13232 pso_num=31 pso_serial#=37
2025-05-07 23:59:54.846740 :941B854D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13232 (legacy spawn)
2025-05-07 23:59:54.860858 :941B854E: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-07 23:59:58.218088 :941B8641: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-07 23:59:58.218265 :941B8646: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-07 23:59:58.219403 :941B864B:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-07 23:59:58.477950 :941B864F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-07 23:59:58.466
2025-05-07 23:59:58.477952 :941B8650:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7708 pso_num=31 pso_serial#=38
2025-05-07 23:59:58.478089 :941B8651:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7708 (legacy spawn)
2025-05-07 23:59:58.492698 :941B8652: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-08 00:06:18.452686 :941C10E7: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-08 00:06:18.452856 :941C10F0: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-08 00:06:18.454092 :941C10F1:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-08 22:00:00.969021 :9482E6F4:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 22:00:00.966
2025-05-08 22:00:00.969022 :9482E6F5:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10284 pso_num=31 pso_serial#=39
2025-05-08 22:00:00.969159 :9482E6F6:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=10284 osp_idx=29 osp_ver=293 osp_pg=0 (spawn #162282)
2025-05-08 22:00:00.969160 :9482E6F7: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-08 22:00:00.969160 :9482E6F8:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=103 time=4012378125
2025-05-08 22:00:00.969161 :9482E6F9:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-08 22:00:00.969162 :9482E6FA:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-08 22:00:00.969163 :9482E6FB:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-08 22:00:00.969164 :9482E6FC:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-08 22:00:00.969164 :9482E6FD:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-08 22:00:00.970015 :9482E6FE: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-08 22:00:31.073609 :9482FADE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-08 23:59:16.045936 :948C687F:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 23:59:16.038
2025-05-08 23:59:16.045938 :948C6880:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2132 pso_num=31 pso_serial#=40
2025-05-08 23:59:16.046079 :948C6881:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=2132 (legacy spawn)
2025-05-08 23:59:16.052512 :948C6882: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-08 23:59:16.052696 :948C688B: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-08 23:59:16.053494 :948C688C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-08 23:59:18.042755 :948C6978:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 23:59:18.040
2025-05-08 23:59:18.042758 :948C6979:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11368 pso_num=31 pso_serial#=41
2025-05-08 23:59:18.042899 :948C697A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11368 (legacy spawn)
2025-05-08 23:59:18.057120 :948C697B: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-08 23:59:51.316925 :948C75A4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-08 23:59:54.866595 :948C76BF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-08 23:59:54.858
2025-05-08 23:59:54.866597 :948C76C0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9648 pso_num=31 pso_serial#=42
2025-05-08 23:59:54.866734 :948C76C1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9648 (legacy spawn)
2025-05-08 23:59:54.880468 :948C76C2: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-09 00:00:28.030464 :948C84B5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 00:00:38.167729 :948C88D1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 00:00:38.164
2025-05-09 00:00:38.167730 :948C88D2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11864 pso_num=31 pso_serial#=43
2025-05-09 00:00:38.167855 :948C88D3:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11864 osp_idx=29 osp_ver=297 osp_pg=0 (spawn #162568)
2025-05-09 00:00:38.167855 :948C88D4: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-09 00:00:38.167856 :948C88D5:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=104 time=4019615312
2025-05-09 00:00:38.167856 :948C88D6:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-09 00:00:38.167857 :948C88D7:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-09 00:00:38.167858 :948C88D8:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-09 00:00:38.167858 :948C88D9:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-09 00:00:38.167859 :948C88DA:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-09 00:00:38.168363 :948C88DB: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-09 00:00:38.168402 :948C88E1: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-09 00:00:38.176707 :948C88F6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 22:00:02.080454 :94F34206:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 22:00:02.078
2025-05-09 22:00:02.080455 :94F34207:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2752 pso_num=31 pso_serial#=44
2025-05-09 22:00:02.080575 :94F34208:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=2752 osp_idx=29 osp_ver=298 osp_pg=0 (spawn #165668)
2025-05-09 22:00:02.080575 :94F34209: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-09 22:00:02.080576 :94F3420A:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=105 time=4098779234
2025-05-09 22:00:02.080577 :94F3420B:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-09 22:00:02.080577 :94F3420C:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-09 22:00:02.080578 :94F3420D:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-09 22:00:02.080579 :94F3420E:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-09 22:00:02.080579 :94F3420F:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-09 22:00:02.081101 :94F34221: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-09 22:00:32.153793 :94F3547F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 23:59:07.216576 :94FC819A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 23:59:07.203
2025-05-09 23:59:07.216578 :94FC819B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12616 pso_num=31 pso_serial#=45
2025-05-09 23:59:07.216711 :94FC819C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12616 (legacy spawn)
2025-05-09 23:59:07.223161 :94FC819D: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-09 23:59:07.223340 :94FC81A2: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-09 23:59:07.224130 :94FC81A7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 23:59:07.232739 :94FC81AA:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 23:59:07.219
2025-05-09 23:59:07.232741 :94FC81AB:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8228 pso_num=31 pso_serial#=46
2025-05-09 23:59:07.232916 :94FC81AC:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8228 (legacy spawn)
2025-05-09 23:59:07.239658 :94FC81AD: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-09 23:59:07.239831 :94FC81B6: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-09 23:59:07.240685 :94FC81B7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 23:59:10.213317 :94FC82BC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 23:59:10.199
2025-05-09 23:59:10.213319 :94FC82BD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12732 pso_num=31 pso_serial#=47
2025-05-09 23:59:10.213453 :94FC82BE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12732 (legacy spawn)
2025-05-09 23:59:10.227432 :94FC82BF: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-09 23:59:43.354816 :94FC8ED9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 23:59:43.619291 :94FC8EDC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 23:59:43.617
2025-05-09 23:59:43.619293 :94FC8EDD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10684 pso_num=31 pso_serial#=48
2025-05-09 23:59:43.619429 :94FC8EDE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10684 (legacy spawn)
2025-05-09 23:59:43.625843 :94FC8EDF: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-09 23:59:43.626013 :94FC8EE8: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-09 23:59:43.626813 :94FC8EE9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 23:59:43.635445 :94FC8EEC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 23:59:43.633
2025-05-09 23:59:43.635448 :94FC8EED:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13208 pso_num=31 pso_serial#=49
2025-05-09 23:59:43.635580 :94FC8EEE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13208 (legacy spawn)
2025-05-09 23:59:43.653243 :94FC8EEF: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-09 23:59:43.653407 :94FC8EF8: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-09 23:59:43.654212 :94FC8EF9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-09 23:59:47.058715 :94FC9020:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-09 23:59:47.053
2025-05-09 23:59:47.058717 :94FC9021:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12440 pso_num=31 pso_serial#=50
2025-05-09 23:59:47.058854 :94FC9022:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12440 (legacy spawn)
2025-05-09 23:59:47.072755 :94FC9023: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-10 00:00:20.198326 :94FC9B6E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 06:00:02.070317 :95189B02:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 06:00:02.068
2025-05-10 06:00:02.070317 :95189B03:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11796 pso_num=31 pso_serial#=51
2025-05-10 06:00:02.070455 :95189B0C:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=11796 osp_idx=29 osp_ver=305 osp_pg=0 (spawn #166801)
2025-05-10 06:00:02.070456 :95189B0D: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-10 06:00:02.070456 :95189B0E:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=106 time=4127579218
2025-05-10 06:00:02.070457 :95189B0F:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-10 06:00:02.070457 :95189B10:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-10 06:00:02.070458 :95189B11:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-10 06:00:02.070458 :95189B12:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-10 06:00:02.070459 :95189B13:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-10 06:00:02.071081 :95189B14: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-10 06:00:32.165627 :9518ADC5:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 23:59:06.906040 :956CB244:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:06.898
2025-05-10 23:59:06.906042 :956CB245:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9288 pso_num=31 pso_serial#=52
2025-05-10 23:59:06.906174 :956CB246:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9288 (legacy spawn)
2025-05-10 23:59:06.912563 :956CB247: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-10 23:59:06.912737 :956CB24C: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-10 23:59:06.913543 :956CB251:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 23:59:08.422502 :956CB28B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:08.414
2025-05-10 23:59:08.422505 :956CB28C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9452 pso_num=31 pso_serial#=53
2025-05-10 23:59:08.422642 :956CB28D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9452 (legacy spawn)
2025-05-10 23:59:08.429289 :956CB28E: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-10 23:59:08.429465 :956CB293: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-10 23:59:08.430336 :956CB298:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 23:59:08.439258 :956CB29B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:08.429
2025-05-10 23:59:08.439260 :956CB29C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11376 pso_num=31 pso_serial#=54
2025-05-10 23:59:08.439395 :956CB29D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11376 (legacy spawn)
2025-05-10 23:59:08.446078 :956CB29E: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-10 23:59:08.446237 :956CB2A7: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-10 23:59:08.447059 :956CB2A8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 23:59:10.086960 :956CB377:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:10.086
2025-05-10 23:59:10.086962 :956CB378:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11412 pso_num=31 pso_serial#=55
2025-05-10 23:59:10.087096 :956CB379:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11412 (legacy spawn)
2025-05-10 23:59:10.101056 :956CB37A: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-10 23:59:13.250236 :956CB495: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-10 23:59:13.250267 :956CB498: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-10 23:59:13.250411 :956CB49B: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-10 23:59:13.251760 :956CB4AC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 23:59:13.509491 :956CB4BB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:13.510
2025-05-10 23:59:13.509493 :956CB4BC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13020 pso_num=31 pso_serial#=56
2025-05-10 23:59:13.509623 :956CB4BD:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13020 (legacy spawn)
2025-05-10 23:59:13.523580 :956CB4BE: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-10 23:59:46.537101 :956CC04A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-10 23:59:46.732890 :956CC04E:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-10 23:59:46.722
2025-05-10 23:59:46.732892 :956CC04F:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=3752 pso_num=31 pso_serial#=57
2025-05-10 23:59:46.733038 :956CC050:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=3752 (legacy spawn)
2025-05-10 23:59:46.746917 :956CC051: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-11 00:00:19.784942 :956CCD0F:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-11 01:00:12.888939 :957160C1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 01:00:12.877
2025-05-11 01:00:12.888941 :957160C2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9992 pso_num=31 pso_serial#=58
2025-05-11 01:00:12.889072 :957160C3:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=9992 osp_idx=29 osp_ver=312 osp_pg=0 (spawn #169493)
2025-05-11 01:00:12.889073 :957160C4: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-11 01:00:12.889073 :957160C5:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=107 time=4195990046
2025-05-11 01:00:12.889074 :957160C6:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-11 01:00:12.889074 :957160C7:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-11 01:00:12.889075 :957160C8:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-11 01:00:12.889075 :957160C9:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-11 01:00:12.889075 :957160CA:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-11 01:00:12.889560 :957160CB: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-11 01:00:12.889602 :957160D1: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-11 01:00:12.896919 :957160DF: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-11 01:00:12.897968 :957160E7: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-11 01:00:12.898608 :957160EF: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-11 01:00:12.899082 :957160F7: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-11 01:00:12.899528 :957160FF: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-11 01:00:12.901993 :95716107: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-11 01:00:12.903177 :9571610F: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-11 01:00:12.952782 :9571612A: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-11 01:00:12.953672 :95716132: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-11 01:00:12.953919 :9571613A: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-11 01:00:12.954133 :95716142: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-11 01:00:12.955813 :9571614A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-05-11 06:00:02.076622 :95884789:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-11 06:00:02.069
2025-05-11 06:00:02.076623 :9588478A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12740 pso_num=31 pso_serial#=59
2025-05-11 06:00:02.076749 :9588478B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=12740 osp_idx=29 osp_ver=313 osp_pg=0 (spawn #170203)
2025-05-11 06:00:02.076750 :9588478C: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-11 06:00:02.076750 :9588478D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=108 time=4213979234
2025-05-11 06:00:02.076751 :9588478E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-05-11 06:00:02.076751 :9588478F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-05-11 06:00:02.076752 :95884790:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-05-11 06:00:02.076752 :95884791:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-11 06:00:02.076753 :95884792:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-11 06:00:02.077247 :95884793: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: 12740, J005)