Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705100739\orcl_j002_12284_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:37513M/63366M, Ph+PgF:43418M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 27
Windows thread id: 12284, image: ORACLE.EXE (J002)


*** 2025-07-05 10:07:39.848
*** SESSION ID:(219.61991) 2025-07-05 10:07:39.848
 

*** 2025-07-05 10:07:39.848
Process diagnostic dump for ORACLE.EXE (J002), OS id=12284,
pid: 27, proc_ser: 170, sid: 219, sess_ser: 61991 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12284
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=14 seq_num=15 snap_id=1
    wait times: snap=0.091382 sec, exc=0.091382 sec, total=0.091382 sec
    wait times: max=0.500000 sec, heur=7.088771 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.000006 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=13 seq_num=14 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.000008 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=12 seq_num=13 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
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 snap_id=1
    wait times: snap=0.503153 sec, exc=0.503153 sec, total=0.503153 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 snap_id=1
    wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 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.000013 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 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
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.501082 sec, exc=0.501082 sec, total=0.501082 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000014 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
Sampled Session History of session 219 serial 61991
---------------------------------------------------
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
---------------------------------------------------
  [8 samples,                                              10:07:32 - 10:07:39]
    idle wait at each sample
  [session created at: 10:07:32]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-05 10:07:39.848
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 27 (osid: 12284, J002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-05 10:02:34.517780 :ADCD21EF:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17548 (legacy spawn)
2025-07-05 10:02:34.524521 :ADCD21F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:02:34.524684 :ADCD21F9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:02:34.525468 :ADCD21FA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:02:37.597024 :ADCD22EA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:02:37.583
2025-07-05 10:02:37.597026 :ADCD22EB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=7020 pso_num=27 pso_serial#=144
2025-07-05 10:02:37.597170 :ADCD22EC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=7020 (legacy spawn)
2025-07-05 10:02:37.604208 :ADCD22ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:02:37.604384 :ADCD22F6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:02:37.605174 :ADCD22F7:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:02:37.623539 :ADCD22FA:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:02:37.615
2025-07-05 10:02:37.623541 :ADCD22FB:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4876 pso_num=27 pso_serial#=145
2025-07-05 10:02:37.623676 :ADCD22FC:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4876 (legacy spawn)
2025-07-05 10:02:37.630447 :ADCD22FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:02:37.630626 :ADCD2305:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:02:37.631467 :ADCD2307:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:02:47.077867 :ADCD2627:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:02:47.072
2025-07-05 10:02:47.077869 :ADCD2628:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13936 pso_num=27 pso_serial#=146
2025-07-05 10:02:47.078010 :ADCD2629:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13936 osp_idx=25 osp_ver=129184 osp_pg=0 (spawn #354168)
2025-07-05 10:02:47.078011 :ADCD262A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-05 10:02:47.078011 :ADCD262B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=218 time=390542970
2025-07-05 10:02:47.078011 :ADCD262C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-05 10:02:47.078012 :ADCD262D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-05 10:02:47.078012 :ADCD262E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-05 10:02:47.078012 :ADCD262F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-05 10:02:47.078013 :ADCD2630:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-05 10:02:47.078631 :ADCD2631:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-05 10:02:47.078677 :ADCD2637:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-07-05 10:02:47.087061 :ADCD2646:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:10.811874 :ADCD2E7B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:10.812
2025-07-05 10:03:10.811877 :ADCD2E7C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11332 pso_num=27 pso_serial#=147
2025-07-05 10:03:10.812026 :ADCD2E7D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11332 (legacy spawn)
2025-07-05 10:03:10.819108 :ADCD2E7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:10.819309 :ADCD2E87:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:10.820535 :ADCD2E88:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:10.835578 :ADCD2E8B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:10.827
2025-07-05 10:03:10.835580 :ADCD2E8C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1892 pso_num=27 pso_serial#=148
2025-07-05 10:03:10.835714 :ADCD2E8D:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1892 (legacy spawn)
2025-07-05 10:03:10.842337 :ADCD2E8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:10.842506 :ADCD2E97:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:10.843442 :ADCD2E98:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:14.008284 :ADCD2F88:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:13.994
2025-07-05 10:03:14.008286 :ADCD2F89:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18296 pso_num=27 pso_serial#=149
2025-07-05 10:03:14.008428 :ADCD2F8A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18296 (legacy spawn)
2025-07-05 10:03:14.015365 :ADCD2F8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:14.015539 :ADCD2F91:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:14.016354 :ADCD2F95:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:14.025568 :ADCD2F98:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:14.025
2025-07-05 10:03:14.025570 :ADCD2F99:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11952 pso_num=27 pso_serial#=150
2025-07-05 10:03:14.025715 :ADCD2F9A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11952 (legacy spawn)
2025-07-05 10:03:14.032527 :ADCD2F9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:14.032690 :ADCD2FA4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:14.033495 :ADCD2FA5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:21.434495 :ADCD323F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:21.425
2025-07-05 10:03:21.434497 :ADCD3240:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9092 pso_num=27 pso_serial#=151
2025-07-05 10:03:21.434645 :ADCD3241:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9092 (legacy spawn)
2025-07-05 10:03:21.441680 :ADCD3242:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:21.441848 :ADCD3247:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:21.442633 :ADCD324C:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:23.741340 :ADCD330A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:23.732
2025-07-05 10:03:23.741341 :ADCD330B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18104 pso_num=27 pso_serial#=152
2025-07-05 10:03:23.741480 :ADCD330C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18104 (legacy spawn)
2025-07-05 10:03:23.748495 :ADCD330D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:23.748654 :ADCD3312:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:23.749510 :ADCD3317:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:03:57.749773 :ADCD3F24:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:03:57.740
2025-07-05 10:03:57.749776 :ADCD3F25:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4488 pso_num=27 pso_serial#=153
2025-07-05 10:03:57.749918 :ADCD3F26:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4488 (legacy spawn)
2025-07-05 10:03:57.756954 :ADCD3F27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:03:57.757122 :ADCD3F2C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:03:57.757944 :ADCD3F31:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:04:23.959699 :ADCD47BE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:04:23.951
2025-07-05 10:04:23.959701 :ADCD47BF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12204 pso_num=27 pso_serial#=154
2025-07-05 10:04:23.959849 :ADCD47C0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12204 (legacy spawn)
2025-07-05 10:04:23.966850 :ADCD47C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:04:23.967026 :ADCD47C6:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:04:23.967831 :ADCD47CB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:04:55.069069 :ADCD5288:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:04:55.056
2025-07-05 10:04:55.069071 :ADCD5289:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17096 pso_num=27 pso_serial#=155
2025-07-05 10:04:55.069212 :ADCD528A:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17096 (legacy spawn)
2025-07-05 10:04:55.076192 :ADCD528B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:04:55.076359 :ADCD5294:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:04:55.077198 :ADCD5295:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:00.810522 :ADCD5489:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:00.811
2025-07-05 10:05:00.810523 :ADCD548A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=4920 pso_num=27 pso_serial#=156
2025-07-05 10:05:00.810678 :ADCD5492:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=4920 (legacy spawn)
2025-07-05 10:05:00.818173 :ADCD54A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:00.818354 :ADCD54AD:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:00.819177 :ADCD54B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:00.828862 :ADCD54B5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:00.826
2025-07-05 10:05:00.828864 :ADCD54B6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1392 pso_num=27 pso_serial#=157
2025-07-05 10:05:00.829004 :ADCD54B7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1392 (legacy spawn)
2025-07-05 10:05:00.836153 :ADCD54B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:00.836320 :ADCD54C1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:00.837213 :ADCD54C2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:04.194013 :ADCD55D0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:04.180
2025-07-05 10:05:04.194015 :ADCD55D1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=876 pso_num=27 pso_serial#=158
2025-07-05 10:05:04.194161 :ADCD55D2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=876 (legacy spawn)
2025-07-05 10:05:04.201183 :ADCD55D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:04.201351 :ADCD55DC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:04.202136 :ADCD55DD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:04.220864 :ADCD55E0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:04.211
2025-07-05 10:05:04.220867 :ADCD55E1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11644 pso_num=27 pso_serial#=159
2025-07-05 10:05:04.221005 :ADCD55E2:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11644 (legacy spawn)
2025-07-05 10:05:04.227741 :ADCD55E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:04.227897 :ADCD55EC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:04.228724 :ADCD55ED:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:06.152788 :ADCD5684:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:06.143
2025-07-05 10:05:06.152790 :ADCD5685:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14456 pso_num=27 pso_serial#=160
2025-07-05 10:05:06.152944 :ADCD5686:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14456 (legacy spawn)
2025-07-05 10:05:06.160222 :ADCD5687:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:06.160395 :ADCD568C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:06.161260 :ADCD5691:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:55.012094 :ADCD708F:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:55.010
2025-07-05 10:05:55.012095 :ADCD7090:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17756 pso_num=27 pso_serial#=161
2025-07-05 10:05:55.012236 :ADCD7091:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17756 (legacy spawn)
2025-07-05 10:05:55.019155 :ADCD7092:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:55.019328 :ADCD7098:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:55.020113 :ADCD709B:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:05:55.029203 :ADCD709E:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:05:55.025
2025-07-05 10:05:55.029205 :ADCD709F:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=2560 pso_num=27 pso_serial#=162
2025-07-05 10:05:55.029343 :ADCD70A0:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=2560 (legacy spawn)
2025-07-05 10:05:55.036094 :ADCD70A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:05:55.036266 :ADCD70A9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:05:55.037085 :ADCD70AB:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:06:13.698881 :ADCD77DF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:06:13.684
2025-07-05 10:06:13.698883 :ADCD77E0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18204 pso_num=27 pso_serial#=163
2025-07-05 10:06:13.699037 :ADCD77E1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18204 (legacy spawn)
2025-07-05 10:06:13.706036 :ADCD77EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:06:13.706199 :ADCD77F7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:06:13.706989 :ADCD77F8:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:06:13.724430 :ADCD77FB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:06:13.715
2025-07-05 10:06:13.724432 :ADCD77FC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14600 pso_num=27 pso_serial#=164
2025-07-05 10:06:13.724583 :ADCD77FD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14600 (legacy spawn)
2025-07-05 10:06:13.731368 :ADCD77FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:06:13.731529 :ADCD7807:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:06:13.732371 :ADCD7808:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:06:14.349795 :ADCD780C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:06:14.340
2025-07-05 10:06:14.349797 :ADCD780D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16332 pso_num=27 pso_serial#=165
2025-07-05 10:06:14.349954 :ADCD780E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16332 (legacy spawn)
2025-07-05 10:06:14.357118 :ADCD780F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:06:14.357294 :ADCD7814:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:06:14.358148 :ADCD7818:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:06:16.806733 :ADCD7917:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:06:16.794
2025-07-05 10:06:16.806735 :ADCD7918:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15440 pso_num=27 pso_serial#=166
2025-07-05 10:06:16.806869 :ADCD7919:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15440 (legacy spawn)
2025-07-05 10:06:16.813493 :ADCD791A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:06:16.813665 :ADCD7923:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:06:16.814467 :ADCD7924:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:06:16.823304 :ADCD7927:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:06:16.809
2025-07-05 10:06:16.823306 :ADCD7928:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11700 pso_num=27 pso_serial#=167
2025-07-05 10:06:16.823447 :ADCD7929:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11700 (legacy spawn)
2025-07-05 10:06:16.830012 :ADCD792A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:06:16.830176 :ADCD7933:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:06:16.830970 :ADCD7934:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:06:59.426954 :ADCD8792:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:06:59.417
2025-07-05 10:06:59.426956 :ADCD8793:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16976 pso_num=27 pso_serial#=168
2025-07-05 10:06:59.427100 :ADCD8794:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16976 (legacy spawn)
2025-07-05 10:06:59.434023 :ADCD8795:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:06:59.434203 :ADCD879D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:06:59.435015 :ADCD879F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:07:22.649851 :ADCD8F53:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:07:22.639
2025-07-05 10:07:22.649853 :ADCD8F54:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15788 pso_num=27 pso_serial#=169
2025-07-05 10:07:22.649993 :ADCD8F55:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15788 (legacy spawn)
2025-07-05 10:07:22.657002 :ADCD8F56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 10:07:22.657166 :ADCD8F5F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-07-05 10:07:22.657973 :ADCD8F60:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted
2025-07-05 10:07:32.771097 :ADCD94F2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-05 10:07:32.765
2025-07-05 10:07:32.771098 :ADCD94F3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12284 pso_num=27 pso_serial#=170
2025-07-05 10:07:32.771229 :ADCD94F9:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12284 osp_idx=25 osp_ver=129208 osp_pg=0 (spawn #354184)
2025-07-05 10:07:32.771230 :ADCD94FA:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-05 10:07:32.771230 :ADCD94FB:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=219 time=390828658
2025-07-05 10:07:32.771231 :ADCD94FC:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs
2025-07-05 10:07:32.771231 :ADCD94FD:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs
2025-07-05 10:07:32.771232 :ADCD94FE:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs
2025-07-05 10:07:32.771232 :ADCD94FF:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-05 10:07:32.771232 :ADCD9500:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-05 10:07:32.771944 :ADCD9501:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] 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 27 (osid: 12284, J002)