Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726220510\orcl_w000_18924_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:36003M/63366M, Ph+PgF:41753M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 18924, image: ORACLE.EXE (W000) *** 2025-07-26 22:05:10.592 *** SESSION ID:(209.5289) 2025-07-26 22:05:10.592 *** 2025-07-26 22:05:10.592 Process diagnostic dump for ORACLE.EXE (W000), OS id=18924, pid: 26, proc_ser: 186, sid: 209, sess_ser: 5289 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=2.731812 sec, exc=2.731812 sec, total=2.731812 sec wait times: max=5.000000 sec, heur=3 min 2 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.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=5.008546 sec, exc=5.008546 sec, total=5.008546 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.004614 sec, exc=5.004614 sec, total=5.004614 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.004647 sec, exc=5.004647 sec, total=5.004647 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.014723 sec, exc=5.014723 sec, total=5.014723 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.001718 sec, exc=5.001718 sec, total=5.001718 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.001123 sec, exc=5.001123 sec, total=5.001123 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.001575 sec, exc=5.001575 sec, total=5.001575 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.014578 sec, exc=5.014578 sec, total=5.014578 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.004622 sec, exc=5.004622 sec, total=5.004622 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 209 serial 5289 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 22:03:10 - 22:05:10] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 22:05:10.592 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 18924, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 19:31:50.143136 :B7580DB4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 19:31:50.143668 :B7580DB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 19:31:50.143730 :B7580DBC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 19:41:51.059019 :B758D271:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 19:41:51.464477 :B758D2CB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 19:41:51.460 2025-07-26 19:41:51.464479 :B758D2CC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19120 pso_num=26 pso_serial#=173 2025-07-26 19:41:51.464667 :B758D2CD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19120 osp_idx=24 osp_ver=688928 osp_pg=0 (spawn #425505) 2025-07-26 19:41:51.464668 :B758D2CE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 19:41:51.464668 :B758D2CF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=159 time=2239687361 2025-07-26 19:41:51.464668 :B758D2D0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 19:41:51.464669 :B758D2D1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 19:41:51.464669 :B758D2D2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 19:41:51.464669 :B758D2D3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 19:41:51.464670 :B758D2D4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 19:41:51.465176 :B758D2D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 19:41:51.465223 :B758D2DD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 19:51:52.210032 :B759978A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 19:51:52.646929 :B75997DA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 19:51:52.642 2025-07-26 19:51:52.646932 :B75997DB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17956 pso_num=26 pso_serial#=174 2025-07-26 19:51:52.647069 :B75997DC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17956 osp_idx=24 osp_ver=688929 osp_pg=0 (spawn #425527) 2025-07-26 19:51:52.647070 :B75997DD:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 19:51:52.647070 :B75997DE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=160 time=2240288548 2025-07-26 19:51:52.647071 :B75997DF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 19:51:52.647071 :B75997E0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 19:51:52.647071 :B75997E1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 19:51:52.647072 :B75997E2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 19:51:52.647072 :B75997E3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 19:51:52.647571 :B75997E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 19:51:52.647617 :B75997EB:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:01:53.324047 :B75A608C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:01:53.688216 :B75A60E8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:01:53.683 2025-07-26 20:01:53.688218 :B75A60E9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=316 pso_num=26 pso_serial#=175 2025-07-26 20:01:53.688350 :B75A60EA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=316 osp_idx=24 osp_ver=688930 osp_pg=0 (spawn #425554) 2025-07-26 20:01:53.688351 :B75A60EB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:01:53.688351 :B75A60EC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=161 time=2240889579 2025-07-26 20:01:53.688352 :B75A60ED:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:01:53.688352 :B75A60EE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:01:53.688352 :B75A60EF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:01:53.688353 :B75A60F0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:01:53.688353 :B75A60F1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:01:53.688861 :B75A60F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:01:53.688915 :B75A60FC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:11:54.389261 :B75B2BAA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:11:54.862776 :B75B2C2F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:11:54.858 2025-07-26 20:11:54.862778 :B75B2C30:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1876 pso_num=26 pso_serial#=176 2025-07-26 20:11:54.862916 :B75B2C31:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=1876 osp_idx=24 osp_ver=688931 osp_pg=0 (spawn #425577) 2025-07-26 20:11:54.862917 :B75B2C32:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:11:54.862917 :B75B2C33:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=162 time=2241490751 2025-07-26 20:11:54.862918 :B75B2C34:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:11:54.862918 :B75B2C35:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:11:54.862918 :B75B2C36:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:11:54.862919 :B75B2C37:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:11:54.862919 :B75B2C38:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:11:54.863407 :B75B2C39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:11:54.863452 :B75B2C40:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:16:30.827644 :B75B84C1:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:21:56.114223 :B75BF038:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:31:56.808242 :B75CB3A6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:31:57.313900 :B75CB45B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:31:57.309 2025-07-26 20:31:57.313902 :B75CB45C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19276 pso_num=26 pso_serial#=177 2025-07-26 20:31:57.314041 :B75CB45D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19276 osp_idx=24 osp_ver=688932 osp_pg=0 (spawn #425619) 2025-07-26 20:31:57.314041 :B75CB45E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:31:57.314042 :B75CB45F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=163 time=2242693204 2025-07-26 20:31:57.314042 :B75CB460:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:31:57.314043 :B75CB461:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:31:57.314043 :B75CB462:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:31:57.314043 :B75CB463:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:31:57.314044 :B75CB464:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:31:57.314538 :B75CB465:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:31:57.314584 :B75CB46C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:41:58.127651 :B75D78E0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:41:58.548764 :B75D798A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:41:58.544 2025-07-26 20:41:58.548766 :B75D798B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16524 pso_num=26 pso_serial#=178 2025-07-26 20:41:58.548912 :B75D798C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16524 osp_idx=24 osp_ver=688933 osp_pg=0 (spawn #425642) 2025-07-26 20:41:58.548912 :B75D798D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:41:58.548913 :B75D798E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=164 time=2243294439 2025-07-26 20:41:58.548913 :B75D798F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:41:58.548914 :B75D7990:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:41:58.548914 :B75D7991:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:41:58.548914 :B75D7992:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:41:58.548915 :B75D7993:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:41:58.549418 :B75D7994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:41:58.549458 :B75D799E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 20:51:59.367132 :B75E3E8D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 20:51:59.856413 :B75E3F38:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 20:51:59.851 2025-07-26 20:51:59.856415 :B75E3F39:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7996 pso_num=26 pso_serial#=179 2025-07-26 20:51:59.856588 :B75E3F3A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7996 osp_idx=24 osp_ver=688934 osp_pg=0 (spawn #425664) 2025-07-26 20:51:59.856589 :B75E3F3B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 20:51:59.856589 :B75E3F3C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=165 time=2243895751 2025-07-26 20:51:59.856590 :B75E3F3D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 20:51:59.856590 :B75E3F3E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 20:51:59.856590 :B75E3F3F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 20:51:59.856591 :B75E3F40:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 20:51:59.856591 :B75E3F41:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 20:51:59.857108 :B75E3F42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 20:51:59.857154 :B75E3F4A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 21:02:00.558710 :B75F045A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:02:00.923123 :B75F04E7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:02:00.918 2025-07-26 21:02:00.923125 :B75F04E8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19264 pso_num=26 pso_serial#=180 2025-07-26 21:02:00.923272 :B75F04E9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19264 osp_idx=24 osp_ver=688935 osp_pg=0 (spawn #425690) 2025-07-26 21:02:00.923273 :B75F04EA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:02:00.923274 :B75F04EB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=166 time=2244496814 2025-07-26 21:02:00.923274 :B75F04EC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:02:00.923275 :B75F04ED:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:02:00.923275 :B75F04EE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:02:00.923276 :B75F04EF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:02:00.923276 :B75F04F0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:02:00.923768 :B75F04F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:02:00.923816 :B75F04F9:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 21:12:01.689861 :B75FCE89:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:12:01.960343 :B75FCEEA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:12:01.955 2025-07-26 21:12:01.960344 :B75FCEEB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17752 pso_num=26 pso_serial#=181 2025-07-26 21:12:01.960488 :B75FCEEC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17752 osp_idx=24 osp_ver=688936 osp_pg=0 (spawn #425713) 2025-07-26 21:12:01.960489 :B75FCEED:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:12:01.960489 :B75FCEEE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=167 time=2245097861 2025-07-26 21:12:01.960490 :B75FCEEF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:12:01.960490 :B75FCEF0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:12:01.960491 :B75FCEF1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:12:01.960491 :B75FCEF2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:12:01.960491 :B75FCEF3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:12:01.960991 :B75FCEF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:12:01.961043 :B75FCEFE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 21:22:02.727004 :B7609542:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:22:03.184865 :B76095A7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:22:03.180 2025-07-26 21:22:03.184867 :B76095A8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17080 pso_num=26 pso_serial#=182 2025-07-26 21:22:03.185007 :B76095A9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17080 osp_idx=24 osp_ver=688937 osp_pg=0 (spawn #425735) 2025-07-26 21:22:03.185008 :B76095AA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:22:03.185009 :B76095AB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=168 time=2245699079 2025-07-26 21:22:03.185009 :B76095AC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:22:03.185009 :B76095AD:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:22:03.185010 :B76095AE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:22:03.185010 :B76095AF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:22:03.185010 :B76095B0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:22:03.185506 :B76095B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:22:03.185550 :B76095B8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 21:32:03.870065 :B76158E8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:32:04.312329 :B761595E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:32:04.307 2025-07-26 21:32:04.312332 :B761595F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6512 pso_num=26 pso_serial#=183 2025-07-26 21:32:04.312478 :B7615960:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6512 osp_idx=24 osp_ver=688938 osp_pg=0 (spawn #425756) 2025-07-26 21:32:04.312479 :B7615961:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:32:04.312479 :B7615962:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=169 time=2246300204 2025-07-26 21:32:04.312480 :B7615963:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:32:04.312480 :B7615964:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:32:04.312481 :B7615965:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:32:04.312481 :B7615966:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:32:04.312482 :B7615967:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:32:04.313029 :B7615968:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:32:04.313077 :B7615970:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 21:42:05.102292 :B7621D93:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:42:05.372776 :B7621DF8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:42:05.367 2025-07-26 21:42:05.372779 :B7621DF9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18136 pso_num=26 pso_serial#=184 2025-07-26 21:42:05.372917 :B7621DFA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18136 osp_idx=24 osp_ver=688939 osp_pg=0 (spawn #425779) 2025-07-26 21:42:05.372918 :B7621DFB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:42:05.372919 :B7621DFC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=170 time=2246901267 2025-07-26 21:42:05.372919 :B7621DFD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:42:05.372920 :B7621DFE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:42:05.372920 :B7621DFF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:42:05.372920 :B7621E00:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:42:05.372921 :B7621E01:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:42:05.373433 :B7621E02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:42:05.373476 :B7621E09:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 21:52:06.127235 :B762E10D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 21:52:06.507295 :B762E16B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 21:52:06.502 2025-07-26 21:52:06.507297 :B762E16C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19200 pso_num=26 pso_serial#=185 2025-07-26 21:52:06.507433 :B762E16D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19200 osp_idx=24 osp_ver=688940 osp_pg=0 (spawn #425801) 2025-07-26 21:52:06.507434 :B762E16E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 21:52:06.507435 :B762E16F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=171 time=2247502408 2025-07-26 21:52:06.507435 :B762E170:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 21:52:06.507435 :B762E171:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 21:52:06.507436 :B762E172:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 21:52:06.507436 :B762E173:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 21:52:06.507436 :B762E174:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 21:52:06.507933 :B762E175:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 21:52:06.507986 :B762E17D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07-26 22:02:07.361305 :B763A829:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 22:02:07.657402 :B763A889:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 22:02:07.652 2025-07-26 22:02:07.657403 :B763A88A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18924 pso_num=26 pso_serial#=186 2025-07-26 22:02:07.657540 :B763A88B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18924 osp_idx=24 osp_ver=688941 osp_pg=0 (spawn #425827) 2025-07-26 22:02:07.657541 :B763A88C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-26 22:02:07.657541 :B763A88D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=172 time=2248103548 2025-07-26 22:02:07.657542 :B763A88E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 22:02:07.657542 :B763A88F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 22:02:07.657543 :B763A890:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 22:02:07.657543 :B763A891:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 22:02:07.657543 :B763A892:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 22:02:07.658035 :B763A893:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-26 22:02:07.658081 :B763A89A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 18924, W000)