Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713081300\orcl_w000_11144_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:36371M/63366M, Ph+PgF:42385M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 11144, image: ORACLE.EXE (W000) *** 2025-07-13 08:13:00.227 *** SESSION ID:(201.39775) 2025-07-13 08:13:00.227 *** 2025-07-13 08:13:00.227 Process diagnostic dump for ORACLE.EXE (W000), OS id=11144, pid: 25, proc_ser: 238, sid: 201, sess_ser: 39775 ------------------------------------------------------------------------------- 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.146591 sec, exc=2.146591 sec, total=2.146591 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.000023 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.013622 sec, exc=5.013622 sec, total=5.013622 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 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.013881 sec, exc=5.013881 sec, total=5.013881 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 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.004633 sec, exc=5.004633 sec, total=5.004633 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 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.014718 sec, exc=5.014718 sec, total=5.014718 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 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.005146 sec, exc=5.005146 sec, total=5.005146 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 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.003510 sec, exc=5.003510 sec, total=5.003510 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 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.000266 sec, exc=5.000266 sec, total=5.000266 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 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.013187 sec, exc=5.013187 sec, total=5.013187 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 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.015528 sec, exc=5.015528 sec, total=5.015528 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 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.014744 sec, exc=5.014744 sec, total=5.014744 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 201 serial 39775 --------------------------------------------------- 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, 08:11:00 - 08:13:00] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 08:13:00.227 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 11144, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 05:59:39.677852 :B14F6FEB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 05:59:40.073150 :B14F7042:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 05:59:40.068 2025-07-13 05:59:40.073152 :B14F7043:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6024 pso_num=25 pso_serial#=224 2025-07-13 05:59:40.073303 :B14F7044:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6024 osp_idx=23 osp_ver=195666 osp_pg=0 (spawn #380271) 2025-07-13 05:59:40.073304 :B14F7045:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 05:59:40.073305 :B14F7046:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=1067155970 2025-07-13 05:59:40.073305 :B14F7047:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 05:59:40.073305 :B14F7048:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 05:59:40.073306 :B14F7049:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 05:59:40.073306 :B14F704A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 05:59:40.073306 :B14F704B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 05:59:40.073803 :B14F704C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 05:59:40.073854 :B14F7054:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:09:40.950788 :B15052F2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:09:41.346062 :B1505351:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:09:41.341 2025-07-13 06:09:41.346063 :B1505352:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15000 pso_num=25 pso_serial#=225 2025-07-13 06:09:41.346203 :B1505353:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15000 osp_idx=23 osp_ver=195667 osp_pg=0 (spawn #380300) 2025-07-13 06:09:41.346203 :B1505354:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 06:09:41.346204 :B1505355:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=1067757236 2025-07-13 06:09:41.346204 :B1505356:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:09:41.346205 :B1505357:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:09:41.346205 :B1505358:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:09:41.346205 :B1505359:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:09:41.346206 :B150535A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:09:41.346689 :B150535B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 06:09:41.346735 :B1505363:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:09:56.375050 :B1505818:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-07-13 06:09:56.375070 :B1505824:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-07-13 06:09:56.375072 :B1505825:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=19, exc=19, tot=19 2025-07-13 06:13:15.473136 :B1509AB6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:19:42.725067 :B1511B44:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:29:43.681024 :B151E27E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:29:44.060764 :B151E2FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:29:44.056 2025-07-13 06:29:44.060766 :B151E2FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14344 pso_num=25 pso_serial#=226 2025-07-13 06:29:44.060904 :B151E2FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14344 osp_idx=23 osp_ver=195668 osp_pg=0 (spawn #380345) 2025-07-13 06:29:44.060905 :B151E2FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 06:29:44.060905 :B151E2FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=1068959954 2025-07-13 06:29:44.060906 :B151E2FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:29:44.060907 :B151E300:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:29:44.060908 :B151E301:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:29:44.060908 :B151E302:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:29:44.060909 :B151E303:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:29:44.061396 :B151E304:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 06:29:44.061442 :B151E30B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:39:44.955821 :B152AA25:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:39:45.078140 :B152AA28:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:39:45.065 2025-07-13 06:39:45.078142 :B152AA29:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11772 pso_num=25 pso_serial#=227 2025-07-13 06:39:45.078278 :B152AA2A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=11772 (legacy spawn) 2025-07-13 06:39:45.084761 :B152AA2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:39:45.084938 :B152AA30:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 06:39:45.085736 :B152AA35:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:39:45.094381 :B152AA38:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:39:45.080 2025-07-13 06:39:45.094383 :B152AA39:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7464 pso_num=25 pso_serial#=228 2025-07-13 06:39:45.094562 :B152AA3A:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=7464 (legacy spawn) 2025-07-13 06:39:45.100970 :B152AA3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 06:39:45.101140 :B152AA44:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 06:39:45.101990 :B152AA45:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:39:45.382532 :B152AAC5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:39:45.378 2025-07-13 06:39:45.382534 :B152AAC6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15484 pso_num=25 pso_serial#=229 2025-07-13 06:39:45.382676 :B152AAC7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15484 osp_idx=23 osp_ver=195671 osp_pg=0 (spawn #380367) 2025-07-13 06:39:45.382676 :B152AAC8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 06:39:45.382677 :B152AAC9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=1069561283 2025-07-13 06:39:45.382677 :B152AACA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:39:45.382677 :B152AACB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:39:45.382678 :B152AACC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:39:45.382678 :B152AACD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:39:45.382678 :B152AACE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:39:45.383178 :B152AACF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 06:39:45.383221 :B152AAD7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:49:46.331225 :B153705E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:49:46.726592 :B15370F0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:49:46.722 2025-07-13 06:49:46.726594 :B15370F1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15404 pso_num=25 pso_serial#=230 2025-07-13 06:49:46.726738 :B15370F2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15404 osp_idx=23 osp_ver=195672 osp_pg=0 (spawn #380390) 2025-07-13 06:49:46.726739 :B15370F3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 06:49:46.726740 :B15370F4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=1070162626 2025-07-13 06:49:46.726740 :B15370F5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:49:46.726741 :B15370F6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:49:46.726741 :B15370F7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:49:46.726741 :B15370F8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:49:46.726742 :B15370F9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:49:46.727278 :B15370FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 06:49:46.727322 :B1537103:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 06:59:47.654918 :B1543667:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 06:59:47.941107 :B15436F3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 06:59:47.935 2025-07-13 06:59:47.941109 :B15436F4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17500 pso_num=25 pso_serial#=231 2025-07-13 06:59:47.941253 :B15436F5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17500 osp_idx=23 osp_ver=195673 osp_pg=0 (spawn #380413) 2025-07-13 06:59:47.941253 :B15436F6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 06:59:47.941254 :B15436F7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=1070763829 2025-07-13 06:59:47.941254 :B15436F8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 06:59:47.941254 :B15436F9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 06:59:47.941255 :B15436FA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 06:59:47.941255 :B15436FB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 06:59:47.941256 :B15436FC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 06:59:47.941772 :B15436FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 06:59:47.941814 :B1543704:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 07:09:48.854037 :B15507C2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 07:09:49.233764 :B1550826:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 07:09:49.228 2025-07-13 07:09:49.233766 :B1550827:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13656 pso_num=25 pso_serial#=232 2025-07-13 07:09:49.233907 :B1550828:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13656 osp_idx=23 osp_ver=195674 osp_pg=0 (spawn #380438) 2025-07-13 07:09:49.233908 :B1550829:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:09:49.233908 :B155082A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=1071365126 2025-07-13 07:09:49.233909 :B155082B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:09:49.233909 :B155082C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:09:49.233909 :B155082D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:09:49.233910 :B155082E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:09:49.233910 :B155082F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:09:49.234397 :B1550830:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 07:09:49.234439 :B1550838:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 07:19:50.126073 :B155CE99:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 07:19:50.568326 :B155CEF6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 07:19:50.563 2025-07-13 07:19:50.568328 :B155CEF7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17612 pso_num=25 pso_serial#=233 2025-07-13 07:19:50.568473 :B155CEF8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17612 osp_idx=23 osp_ver=195675 osp_pg=0 (spawn #380461) 2025-07-13 07:19:50.568474 :B155CEF9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:19:50.568474 :B155CEFA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=221 time=1071966454 2025-07-13 07:19:50.568474 :B155CEFB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:19:50.568475 :B155CEFC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:19:50.568475 :B155CEFD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:19:50.568475 :B155CEFE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:19:50.568476 :B155CEFF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:19:50.568981 :B155CF00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 07:19:50.569026 :B155CF07:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 07:29:51.464544 :B156928C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 07:29:52.016208 :B15692EF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 07:29:52.011 2025-07-13 07:29:52.016210 :B15692F0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17424 pso_num=25 pso_serial#=234 2025-07-13 07:29:52.016346 :B15692F1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17424 osp_idx=23 osp_ver=195676 osp_pg=0 (spawn #380484) 2025-07-13 07:29:52.016347 :B15692F2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:29:52.016347 :B15692F3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=1072567908 2025-07-13 07:29:52.016347 :B15692F4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:29:52.016347 :B15692F5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:29:52.016348 :B15692F6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:29:52.016348 :B15692F7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:29:52.016348 :B15692F8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:29:52.016833 :B15692F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 07:29:52.016881 :B1569301:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 07:39:52.892276 :B15757E4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 07:39:53.334519 :B157583D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 07:39:53.329 2025-07-13 07:39:53.334521 :B157583E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18028 pso_num=25 pso_serial#=235 2025-07-13 07:39:53.334679 :B157583F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18028 osp_idx=23 osp_ver=195677 osp_pg=0 (spawn #380504) 2025-07-13 07:39:53.334680 :B1575840:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:39:53.334680 :B1575841:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=223 time=1073169236 2025-07-13 07:39:53.334681 :B1575842:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:39:53.334681 :B1575843:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:39:53.334683 :B1575844:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:39:53.334683 :B1575845:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:39:53.334684 :B1575846:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:39:53.335192 :B1575847:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 07:39:53.335246 :B1575850:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 07:49:54.311077 :B1581AF2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 07:49:54.800236 :B1581B4B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 07:49:54.796 2025-07-13 07:49:54.800238 :B1581B4C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2512 pso_num=25 pso_serial#=236 2025-07-13 07:49:54.800373 :B1581B4D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2512 osp_idx=23 osp_ver=195678 osp_pg=0 (spawn #380528) 2025-07-13 07:49:54.800374 :B1581B4E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:49:54.800374 :B1581B4F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=224 time=1073770689 2025-07-13 07:49:54.800374 :B1581B50:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:49:54.800375 :B1581B51:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:49:54.800375 :B1581B52:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:49:54.800375 :B1581B53:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:49:54.800376 :B1581B54:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:49:54.800930 :B1581B55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 07:49:54.800977 :B1581B5D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 07:59:55.716471 :B158DE97:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 07:59:56.299226 :B158DEFF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 07:59:56.294 2025-07-13 07:59:56.299230 :B158DF00:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15364 pso_num=25 pso_serial#=237 2025-07-13 07:59:56.299404 :B158DF01:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15364 osp_idx=23 osp_ver=195679 osp_pg=0 (spawn #380551) 2025-07-13 07:59:56.299405 :B158DF02:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 07:59:56.299406 :B158DF03:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=225 time=1074372189 2025-07-13 07:59:56.299406 :B158DF04:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 07:59:56.299406 :B158DF05:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 07:59:56.299407 :B158DF06:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 07:59:56.299407 :B158DF07:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 07:59:56.299407 :B158DF08:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 07:59:56.299906 :B158DF09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 07:59:56.299949 :B158DF10:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-13 08:09:57.291225 :B159AC4D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-13 08:09:57.795913 :B159ACA9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-13 08:09:57.791 2025-07-13 08:09:57.795915 :B159ACAA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11144 pso_num=25 pso_serial#=238 2025-07-13 08:09:57.796054 :B159ACAB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11144 osp_idx=23 osp_ver=195680 osp_pg=0 (spawn #380576) 2025-07-13 08:09:57.796055 :B159ACAC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 08:09:57.796056 :B159ACAD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=226 time=1074973689 2025-07-13 08:09:57.796056 :B159ACAE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 08:09:57.796056 :B159ACAF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 08:09:57.796057 :B159ACB0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 08:09:57.796057 :B159ACB1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 08:09:57.796057 :B159ACB2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 08:09:57.796548 :B159ACB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 08:09:57.796598 :B159ACB9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 11144, W000)