Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504060008\orcl_j005_9532_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:37551M/63366M, Ph+PgF:45030M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 9532, image: ORACLE.EXE (J005) *** 2025-05-04 06:00:08.870 *** SESSION ID:(249.551) 2025-05-04 06:00:08.870 *** 2025-05-04 06:00:08.870 Process diagnostic dump for ORACLE.EXE (J005), OS id=9532, pid: 31, proc_ser: 12, sid: 249, sess_ser: 551 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 9532 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.313210 sec, exc=0.313210 sec, total=0.313210 sec wait times: max=0.500000 sec, heur=6.819500 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499963 sec, exc=0.499963 sec, total=0.499963 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510124 sec, exc=0.510124 sec, total=0.510124 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000015 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.504582 sec, exc=0.504582 sec, total=0.504582 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 249 serial 551 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 06:00:02 - 06:00:08] 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-04 06:00:08.870 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 9532, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-01 00:00:55.789349 :91010670: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-01 00:00:55.791719 :91010678: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-01 00:00:55.792905 :91010680: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-01 00:00:55.842421 :91010688: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-01 00:00:55.843328 :91010690: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-01 00:00:55.843554 :91010698: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-01 00:00:55.843771 :910106A0: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-01 00:00:55.845461 :910106A8:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 00:01:02.842550 :91010904:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 00:01:02.841 2025-05-01 00:01:02.842553 :91010905:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9940 pso_num=31 pso_serial#=247 2025-05-01 00:01:02.842683 :91010906:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9940 (legacy spawn) 2025-05-01 00:01:02.849436 :91010914: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-01 00:01:02.849580 :9101091D: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-01 00:01:02.850404 :9101091E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 00:04:54.945518 :910155FB:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 00:04:54.933 2025-05-01 00:04:54.945520 :910155FC:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9996 pso_num=31 pso_serial#=248 2025-05-01 00:04:54.945650 :910155FD:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=9996 osp_idx=29 osp_ver=247 osp_pg=0 (spawn #135464) 2025-05-01 00:04:54.945651 :910155FE: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-01 00:04:54.945652 :910155FF:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=88 time=3328684421 2025-05-01 00:04:54.945653 :91015600:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-01 00:04:54.945654 :91015601:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-01 00:04:54.945654 :91015602:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-01 00:04:54.945655 :91015603:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-01 00:04:54.945655 :91015604:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-01 00:04:54.946251 :91015605: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-01 00:04:54.946291 :9101560B: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-01 00:04:54.954013 :9101561D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 22:00:00.248533 :91670B63:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 22:00:00.241 2025-05-01 22:00:00.248534 :91670B64:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=4876 pso_num=31 pso_serial#=249 2025-05-01 22:00:00.248646 :91670B65:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=4876 osp_idx=29 osp_ver=248 osp_pg=0 (spawn #138546) 2025-05-01 22:00:00.248647 :91670B66: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-01 22:00:00.248647 :91670B67:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=89 time=3407589734 2025-05-01 22:00:00.248648 :91670B68:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-01 22:00:00.248648 :91670B69:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-01 22:00:00.248648 :91670B6A:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-01 22:00:00.248649 :91670B6B:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-01 22:00:00.248649 :91670B6C:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-01 22:00:00.249161 :91670B70: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-01 22:00:30.332670 :91671D41:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 23:59:09.809221 :9170C292:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 23:59:09.808 2025-05-01 23:59:09.809223 :9170C293:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10844 pso_num=31 pso_serial#=250 2025-05-01 23:59:09.809367 :9170C294:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10844 (legacy spawn) 2025-05-01 23:59:09.823516 :9170C295: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-01 23:59:12.940984 :9170C399: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-01 23:59:12.941162 :9170C39E: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-01 23:59:12.942305 :9170C3A3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 23:59:13.192453 :9170C3A6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 23:59:13.183 2025-05-01 23:59:13.192455 :9170C3A7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11428 pso_num=31 pso_serial#=251 2025-05-01 23:59:13.192589 :9170C3A8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11428 (legacy spawn) 2025-05-01 23:59:13.206718 :9170C3A9: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-01 23:59:46.510465 :9170CF6E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 23:59:46.629892 :9170CF9B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 23:59:46.617 2025-05-01 23:59:46.629895 :9170CF9C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7548 pso_num=31 pso_serial#=252 2025-05-01 23:59:46.630072 :9170CF9D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7548 (legacy spawn) 2025-05-01 23:59:46.643554 :9170CF9E: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-01 23:59:49.775772 :9170D08D: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-01 23:59:49.775956 :9170D092: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-01 23:59:49.777035 :9170D097:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-01 23:59:50.038503 :9170D0A6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-01 23:59:50.035 2025-05-01 23:59:50.038505 :9170D0A7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11452 pso_num=31 pso_serial#=253 2025-05-01 23:59:50.038642 :9170D0A8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11452 (legacy spawn) 2025-05-01 23:59:50.052706 :9170D0A9: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-02 00:00:23.327167 :9170DDAC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 01:00:14.773779 :917576FC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 01:00:14.765 2025-05-02 01:00:14.773781 :917576FD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8784 pso_num=31 pso_serial#=254 2025-05-02 01:00:14.773909 :917576FE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8784 (legacy spawn) 2025-05-02 01:00:14.797832 :917576FF: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-02 01:00:14.798152 :91757708: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-02 01:00:14.798996 :91757709:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 01:00:15.781139 :91757779:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 01:00:15.768 2025-05-02 01:00:15.781141 :9175777A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10380 pso_num=31 pso_serial#=255 2025-05-02 01:00:15.781273 :9175777B:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=10380 osp_idx=29 osp_ver=254 osp_pg=0 (spawn #138979) 2025-05-02 01:00:15.781274 :9175777C: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-02 01:00:15.781274 :9175777D:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=90 time=3418405250 2025-05-02 01:00:15.781275 :9175777E:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 01:00:15.781275 :9175777F:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 01:00:15.781276 :91757780:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 01:00:15.781276 :91757781:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 01:00:15.781276 :91757782:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 01:00:15.781832 :91757783: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-02 01:00:15.781875 :91757789: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-02 01:00:15.789131 :91757797: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-02 01:00:15.790241 :9175779F: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-02 01:00:15.790815 :917577A7: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-02 01:00:15.791290 :917577AF: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-02 01:00:15.791724 :917577B7: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-02 01:00:15.794162 :917577BF: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-02 01:00:15.795263 :917577C7: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-02 01:00:15.842964 :917577CF: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-02 01:00:15.844444 :917577D7: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-02 01:00:15.845301 :917577DF: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-02 01:00:15.845535 :917577E7: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-02 01:00:15.845759 :917577EF: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-02 01:00:15.847548 :917577F7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 22:00:01.251673 :91D8388A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 22:00:01.245 2025-05-02 22:00:01.251674 :91D8388B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7888 pso_num=31 pso_serial#=1 2025-05-02 22:00:01.251808 :91D8388C:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=7888 osp_idx=29 osp_ver=255 osp_pg=0 (spawn #141930) 2025-05-02 22:00:01.251809 :91D8388D: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-02 22:00:01.251810 :91D8388E:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=91 time=3493990734 2025-05-02 22:00:01.251810 :91D8388F:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 22:00:01.251811 :91D83890:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 22:00:01.251812 :91D83891:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 22:00:01.251812 :91D83892:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 22:00:01.251813 :91D83893:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 22:00:01.252407 :91D83894: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-02 22:00:31.356293 :91D84A61:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 23:59:08.876304 :91E207B5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 23:59:08.867 2025-05-02 23:59:08.876306 :91E207B6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10868 pso_num=31 pso_serial#=2 2025-05-02 23:59:08.876454 :91E207B7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10868 (legacy spawn) 2025-05-02 23:59:08.883097 :91E207B8: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-02 23:59:08.883266 :91E207BD: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-02 23:59:08.884091 :91E207C2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 23:59:10.022891 :91E20883:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 23:59:10.021 2025-05-02 23:59:10.022893 :91E20884:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10080 pso_num=31 pso_serial#=3 2025-05-02 23:59:10.023030 :91E20885:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10080 (legacy spawn) 2025-05-02 23:59:10.037064 :91E20886: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-02 23:59:13.279567 :91E209A2: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-02 23:59:13.279741 :91E209A8: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-02 23:59:13.281026 :91E209AC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 23:59:13.528789 :91E209B0:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 23:59:13.522 2025-05-02 23:59:13.528792 :91E209B1:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11052 pso_num=31 pso_serial#=4 2025-05-02 23:59:13.528931 :91E209B2:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11052 (legacy spawn) 2025-05-02 23:59:13.543079 :91E209B3: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-02 23:59:46.731736 :91E215A7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 23:59:55.018547 :91E218E6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 23:59:55.009 2025-05-02 23:59:55.018549 :91E218E7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6488 pso_num=31 pso_serial#=5 2025-05-02 23:59:55.018690 :91E218E8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=6488 (legacy spawn) 2025-05-02 23:59:55.032433 :91E218E9: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-02 23:59:58.298410 :91E219EC: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-02 23:59:58.298591 :91E219F1: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-02 23:59:58.299799 :91E219F6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-02 23:59:58.556113 :91E21A06:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-02 23:59:58.552 2025-05-02 23:59:58.556116 :91E21A07:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=10332 pso_num=31 pso_serial#=6 2025-05-02 23:59:58.556258 :91E21A08:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=10332 (legacy spawn) 2025-05-02 23:59:58.570472 :91E21A09: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-03 00:06:18.509516 :91E2A7C6: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-03 00:06:18.509686 :91E2A7CB: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-03 00:06:18.510956 :91E2A7D0:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-03 06:00:02.060126 :91FED9F1:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-03 06:00:02.051 2025-05-03 06:00:02.060127 :91FED9F2:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=2860 pso_num=31 pso_serial#=7 2025-05-03 06:00:02.060271 :91FED9F3:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=2860 osp_idx=29 osp_ver=261 osp_pg=0 (spawn #143069) 2025-05-03 06:00:02.060271 :91FED9F4: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-03 06:00:02.060272 :91FED9F5:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=92 time=3522791531 2025-05-03 06:00:02.060273 :91FED9F6:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 06:00:02.060274 :91FED9F7:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 06:00:02.060274 :91FED9F8:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 06:00:02.060274 :91FED9F9:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 06:00:02.060275 :91FED9FA:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 06:00:02.060811 :91FED9FB: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-03 06:00:32.145511 :91FEEC71:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-03 23:59:17.824776 :9255E32A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-03 23:59:17.820 2025-05-03 23:59:17.824778 :9255E32B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=12108 pso_num=31 pso_serial#=8 2025-05-03 23:59:17.824932 :9255E32C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=12108 (legacy spawn) 2025-05-03 23:59:17.839378 :9255E32D: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-03 23:59:21.263484 :9255E4A2: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-03 23:59:21.263652 :9255E4AB: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-03 23:59:21.264755 :9255E4AC:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-03 23:59:21.519277 :9255E4AF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-03 23:59:21.507 2025-05-03 23:59:21.519279 :9255E4B0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=1164 pso_num=31 pso_serial#=9 2025-05-03 23:59:21.519416 :9255E4B1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=1164 (legacy spawn) 2025-05-03 23:59:21.533722 :9255E4B2: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-03 23:59:54.703190 :9255F1C3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-03 23:59:54.781095 :9255F1C6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-03 23:59:54.778 2025-05-03 23:59:54.781098 :9255F1C7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11748 pso_num=31 pso_serial#=10 2025-05-03 23:59:54.781227 :9255F1C8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11748 (legacy spawn) 2025-05-03 23:59:54.797756 :9255F1C9: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-03 23:59:58.086987 :9255F2D5: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-03 23:59:58.087163 :9255F2DA: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-03 23:59:58.088347 :9255F2DF:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-03 23:59:58.338591 :9255F2F5:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-03 23:59:58.329 2025-05-03 23:59:58.338592 :9255F2F6:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11568 pso_num=31 pso_serial#=11 2025-05-03 23:59:58.338724 :9255F2F7:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11568 (legacy spawn) 2025-05-03 23:59:58.352844 :9255F2F8: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-04 00:06:18.311541 :92568049: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-04 00:06:18.311726 :92568052: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-04 00:06:18.312854 :92568053:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-05-04 06:00:02.050521 :92728050:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 05-04 06:00:02.043 2025-05-04 06:00:02.050522 :92728051:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9532 pso_num=31 pso_serial#=12 2025-05-04 06:00:02.050650 :92728057:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=9532 osp_idx=29 osp_ver=266 osp_pg=0 (spawn #146469) 2025-05-04 06:00:02.050651 :92728058: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-04 06:00:02.050651 :92728059:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=93 time=3609179218 2025-05-04 06:00:02.050652 :9272805A:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 06:00:02.050652 :9272805B:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 06:00:02.050653 :9272805C:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 06:00:02.050654 :9272805D:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 06:00:02.050654 :9272805E:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 06:00:02.051271 :9272805F: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: 9532, J005)