Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250502220007\orcl_w000_11956_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:37547M/63366M, Ph+PgF:45065M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 11956, image: ORACLE.EXE (W000) *** 2025-05-02 22:00:07.712 *** SESSION ID:(177.20509) 2025-05-02 22:00:07.712 *** 2025-05-02 22:00:07.712 Process diagnostic dump for ORACLE.EXE (W000), OS id=11956, pid: 22, proc_ser: 188, sid: 177, sess_ser: 20509 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=1.955955 sec, exc=1.955955 sec, total=1.955955 sec wait times: max=5.000000 sec, heur=11.966203 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.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=5.004638 sec, exc=5.004638 sec, total=5.004638 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=5.005567 sec, exc=5.005567 sec, total=5.005567 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.003973 sec of elapsed time 2: waited for 'class slave wait' slave id=0x11bbeb90, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000041 sec, exc=0.000041 sec, total=0.000041 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 177 serial 20509 --------------------------------------------------- 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 --------------------------------------------------- [12 samples, 21:59:55 - 22:00:07] idle wait at each sample [session created at: 21:59:55] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-02 22:00:07.712 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 11956, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-02 19:29:36.076044 :91CC6AC6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 19:29:36.076045 :91CC6AC7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 19:29:36.076539 :91CC6AC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 19:29:36.076584 :91CC6AD1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 19:39:36.937123 :91CD3349:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 19:39:37.254279 :91CD3388:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 19:39:37.249 2025-05-02 19:39:37.254281 :91CD3389:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9496 pso_num=22 pso_serial#=175 2025-05-02 19:39:37.254418 :91CD338A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9496 osp_idx=0 osp_ver=149860 osp_pg=0 (spawn #141595) 2025-05-02 19:39:37.254418 :91CD338B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 19:39:37.254419 :91CD338C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=48 time=3485566734 2025-05-02 19:39:37.254419 :91CD338D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 19:39:37.254419 :91CD338E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 19:39:37.254420 :91CD338F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 19:39:37.254420 :91CD3390:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 19:39:37.254420 :91CD3391:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 19:39:37.254906 :91CD3392:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 19:39:37.254949 :91CD339C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 19:49:38.211828 :91CDFB20:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 19:49:38.591595 :91CDFB67:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 19:49:38.586 2025-05-02 19:49:38.591597 :91CDFB68:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10904 pso_num=22 pso_serial#=176 2025-05-02 19:49:38.591735 :91CDFB69:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10904 osp_idx=0 osp_ver=149861 osp_pg=0 (spawn #141618) 2025-05-02 19:49:38.591737 :91CDFB6A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 19:49:38.591737 :91CDFB6B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=49 time=3486168078 2025-05-02 19:49:38.591738 :91CDFB6C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 19:49:38.591738 :91CDFB6D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 19:49:38.591738 :91CDFB6E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 19:49:38.591739 :91CDFB6F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 19:49:38.591739 :91CDFB70:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 19:49:38.592229 :91CDFB71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 19:49:38.592280 :91CDFB7B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 19:59:39.567709 :91CEC279:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 19:59:39.832578 :91CEC2B4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 19:59:39.827 2025-05-02 19:59:39.832580 :91CEC2B5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9028 pso_num=22 pso_serial#=177 2025-05-02 19:59:39.832720 :91CEC2B6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9028 osp_idx=0 osp_ver=149862 osp_pg=0 (spawn #141641) 2025-05-02 19:59:39.832720 :91CEC2B7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 19:59:39.832721 :91CEC2B8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=50 time=3486769312 2025-05-02 19:59:39.832721 :91CEC2B9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 19:59:39.832721 :91CEC2BA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 19:59:39.832722 :91CEC2BB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 19:59:39.832722 :91CEC2BC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 19:59:39.832722 :91CEC2BD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 19:59:39.833224 :91CEC2BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 19:59:39.833270 :91CEC2C6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 20:09:40.677824 :91CF94D8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 20:09:41.088765 :91CF9525:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 20:09:41.084 2025-05-02 20:09:41.088767 :91CF9526:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11348 pso_num=22 pso_serial#=178 2025-05-02 20:09:41.088905 :91CF9527:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11348 osp_idx=0 osp_ver=149863 osp_pg=0 (spawn #141666) 2025-05-02 20:09:41.088905 :91CF9528:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 20:09:41.088906 :91CF9529:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=51 time=3487370578 2025-05-02 20:09:41.088906 :91CF952A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 20:09:41.088906 :91CF952B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 20:09:41.088907 :91CF952C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 20:09:41.088907 :91CF952D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 20:09:41.088908 :91CF952E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 20:09:41.089404 :91CF952F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 20:09:41.089455 :91CF9539:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 20:18:43.570136 :91D0487E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-02 20:19:42.395190 :91D05D0F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-02 20:29:43.355747 :91D12472:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 20:29:43.704072 :91D124EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 20:29:43.699 2025-05-02 20:29:43.704074 :91D124ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8760 pso_num=22 pso_serial#=179 2025-05-02 20:29:43.704216 :91D124EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8760 osp_idx=0 osp_ver=149864 osp_pg=0 (spawn #141713) 2025-05-02 20:29:43.704217 :91D124EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 20:29:43.704218 :91D124F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=52 time=3488573187 2025-05-02 20:29:43.704218 :91D124F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 20:29:43.704218 :91D124F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 20:29:43.704219 :91D124F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 20:29:43.704219 :91D124F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 20:29:43.704219 :91D124F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 20:29:43.704719 :91D124F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 20:29:43.704768 :91D124FE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 20:39:44.695716 :91D1EC97:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 20:39:44.997266 :91D1ED1B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 20:39:44.992 2025-05-02 20:39:44.997268 :91D1ED1C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8224 pso_num=22 pso_serial#=180 2025-05-02 20:39:44.997407 :91D1ED1D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8224 osp_idx=0 osp_ver=149865 osp_pg=0 (spawn #141735) 2025-05-02 20:39:44.997408 :91D1ED1E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 20:39:44.997408 :91D1ED1F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=53 time=3489174484 2025-05-02 20:39:44.997409 :91D1ED20:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 20:39:44.997409 :91D1ED21:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 20:39:44.997409 :91D1ED22:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 20:39:44.997410 :91D1ED23:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 20:39:44.997410 :91D1ED24:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 20:39:44.997895 :91D1ED25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 20:39:44.997944 :91D1ED2C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 20:49:45.937214 :91D2B372:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 20:49:46.332501 :91D2B3B1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 20:49:46.328 2025-05-02 20:49:46.332503 :91D2B3B2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11172 pso_num=22 pso_serial#=181 2025-05-02 20:49:46.332633 :91D2B3B3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11172 osp_idx=0 osp_ver=149866 osp_pg=0 (spawn #141758) 2025-05-02 20:49:46.332634 :91D2B3B4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 20:49:46.332635 :91D2B3B5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=54 time=3489775812 2025-05-02 20:49:46.332635 :91D2B3B6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 20:49:46.332636 :91D2B3B7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 20:49:46.332636 :91D2B3B8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 20:49:46.332636 :91D2B3B9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 20:49:46.332637 :91D2B3BA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 20:49:46.333196 :91D2B3BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 20:49:46.333240 :91D2B3C1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 20:59:47.272870 :91D37AF1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 20:59:47.652413 :91D37B2B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 20:59:47.648 2025-05-02 20:59:47.652414 :91D37B2C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11284 pso_num=22 pso_serial#=182 2025-05-02 20:59:47.652545 :91D37B2D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11284 osp_idx=0 osp_ver=149867 osp_pg=0 (spawn #141781) 2025-05-02 20:59:47.652546 :91D37B2E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 20:59:47.652547 :91D37B2F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=55 time=3490377140 2025-05-02 20:59:47.652547 :91D37B30:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 20:59:47.652547 :91D37B31:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 20:59:47.652548 :91D37B32:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 20:59:47.652548 :91D37B33:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 20:59:47.652548 :91D37B34:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 20:59:47.653039 :91D37B35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 20:59:47.653083 :91D37B3D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 21:09:48.415389 :91D44A1F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 21:09:48.888670 :91D44A58:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 21:09:48.884 2025-05-02 21:09:48.888672 :91D44A59:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11396 pso_num=22 pso_serial#=183 2025-05-02 21:09:48.888806 :91D44A5A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11396 osp_idx=0 osp_ver=149868 osp_pg=0 (spawn #141806) 2025-05-02 21:09:48.888807 :91D44A5B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 21:09:48.888807 :91D44A5C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=56 time=3490978375 2025-05-02 21:09:48.888808 :91D44A5D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 21:09:48.888808 :91D44A5E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 21:09:48.888808 :91D44A5F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 21:09:48.888808 :91D44A60:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 21:09:48.888809 :91D44A61:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 21:09:48.889346 :91D44A62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 21:09:48.889394 :91D44A6C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 21:19:49.686695 :91D51586:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 21:19:50.201480 :91D515CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 21:19:50.196 2025-05-02 21:19:50.201481 :91D515CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11624 pso_num=22 pso_serial#=184 2025-05-02 21:19:50.201617 :91D515CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11624 osp_idx=0 osp_ver=149869 osp_pg=0 (spawn #141831) 2025-05-02 21:19:50.201618 :91D515CD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 21:19:50.201618 :91D515CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=57 time=3491579687 2025-05-02 21:19:50.201619 :91D515CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 21:19:50.201619 :91D515D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 21:19:50.201619 :91D515D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 21:19:50.201620 :91D515D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 21:19:50.201620 :91D515D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 21:19:50.202089 :91D515D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 21:19:50.202132 :91D515DC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 21:29:51.051158 :91D5DCA5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 21:29:51.477646 :91D5DCE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 21:29:51.472 2025-05-02 21:29:51.477648 :91D5DCE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10224 pso_num=22 pso_serial#=185 2025-05-02 21:29:51.477782 :91D5DCE2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10224 osp_idx=0 osp_ver=149870 osp_pg=0 (spawn #141854) 2025-05-02 21:29:51.477783 :91D5DCE3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 21:29:51.477783 :91D5DCE4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=58 time=3492180953 2025-05-02 21:29:51.477783 :91D5DCE5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 21:29:51.477784 :91D5DCE6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 21:29:51.477784 :91D5DCE7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 21:29:51.477785 :91D5DCE8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 21:29:51.477785 :91D5DCE9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 21:29:51.478284 :91D5DCEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 21:29:51.478328 :91D5DCF2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 21:39:52.421439 :91D6A40F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 21:39:52.935759 :91D6A457:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 21:39:52.931 2025-05-02 21:39:52.935761 :91D6A458:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10940 pso_num=22 pso_serial#=186 2025-05-02 21:39:52.935896 :91D6A459:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10940 osp_idx=0 osp_ver=149871 osp_pg=0 (spawn #141876) 2025-05-02 21:39:52.935896 :91D6A45A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 21:39:52.935897 :91D6A45B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=59 time=3492782421 2025-05-02 21:39:52.935897 :91D6A45C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 21:39:52.935898 :91D6A45D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 21:39:52.935898 :91D6A45E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 21:39:52.935898 :91D6A45F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 21:39:52.935899 :91D6A460:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 21:39:52.936384 :91D6A461:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 21:39:52.936426 :91D6A469:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 21:49:53.796719 :91D76AFF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 21:49:54.379606 :91D76B3A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 21:49:54.375 2025-05-02 21:49:54.379608 :91D76B3B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11960 pso_num=22 pso_serial#=187 2025-05-02 21:49:54.379747 :91D76B3C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11960 osp_idx=0 osp_ver=149872 osp_pg=0 (spawn #141899) 2025-05-02 21:49:54.379747 :91D76B3D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 21:49:54.379748 :91D76B3E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=60 time=3493383859 2025-05-02 21:49:54.379748 :91D76B3F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 21:49:54.379749 :91D76B40:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 21:49:54.379749 :91D76B41:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 21:49:54.379749 :91D76B42:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 21:49:54.379750 :91D76B43:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 21:49:54.380261 :91D76B44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 21:49:54.380305 :91D76B4C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-02 21:59:55.229437 :91D83239:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-02 21:59:55.749722 :91D83280:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-02 21:59:55.745 2025-05-02 21:59:55.749724 :91D83281:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11956 pso_num=22 pso_serial#=188 2025-05-02 21:59:55.749857 :91D83282:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11956 osp_idx=0 osp_ver=149873 osp_pg=0 (spawn #141922) 2025-05-02 21:59:55.749858 :91D83283:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-02 21:59:55.749858 :91D83284:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=61 time=3493985234 2025-05-02 21:59:55.749859 :91D83285:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-02 21:59:55.749859 :91D83286:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-02 21:59:55.749860 :91D83287:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-02 21:59:55.749860 :91D83288:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-02 21:59:55.749860 :91D83289:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-02 21:59:55.750355 :91D8328A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-02 21:59:55.750402 :91D83292:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 11956, W000)