Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250726180440\orcl_w000_7568_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:36025M/63366M, Ph+PgF:41813M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 7568, image: ORACLE.EXE (W000) *** 2025-07-26 18:04:40.342 *** SESSION ID:(209.5245) 2025-07-26 18:04:40.342 *** 2025-07-26 18:04:40.342 Process diagnostic dump for ORACLE.EXE (W000), OS id=7568, pid: 26, proc_ser: 164, sid: 209, sess_ser: 5245 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=4.658868 sec, exc=4.658868 sec, total=4.658868 sec wait times: max=5.000000 sec, heur=2 min 59 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.000031 sec since current wait 0: 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.004623 sec, exc=5.004623 sec, total=5.004623 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 1: 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.004626 sec, exc=5.004626 sec, total=5.004626 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 2: 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.007237 sec, exc=5.007237 sec, total=5.007237 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 3: 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.001417 sec, exc=5.001417 sec, total=5.001417 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: 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.001259 sec, exc=5.001259 sec, total=5.001259 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: 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.013869 sec, exc=5.013869 sec, total=5.013869 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: 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.004612 sec, exc=5.004612 sec, total=5.004612 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: 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.003593 sec, exc=5.003593 sec, total=5.003593 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: 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.000153 sec, exc=5.000153 sec, total=5.000153 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.008315 sec, exc=5.008315 sec, total=5.008315 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time Sampled Session History of session 209 serial 5245 --------------------------------------------------- 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, 18:02:40 - 18:04:40] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-26 18:04:40.342 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 7568, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-26 15:41:24.815153 :B746074F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15184 pso_num=26 pso_serial#=150 2025-07-26 15:41:24.815293 :B7460750:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15184 osp_idx=24 osp_ver=688905 osp_pg=0 (spawn #424954) 2025-07-26 15:41:24.815294 :B7460751: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 15:41:24.815295 :B7460752:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=137 time=2225260704 2025-07-26 15:41:24.815295 :B7460753:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 15:41:24.815295 :B7460754:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 15:41:24.815296 :B7460755:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 15:41:24.815296 :B7460756:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 15:41:24.815296 :B7460757:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 15:41:24.815784 :B7460758: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 15:41:24.815828 :B7460760: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 15:51:25.531320 :B746CD04:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 15:51:25.911142 :B746CD55:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 15:51:25.906 2025-07-26 15:51:25.911144 :B746CD56:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4556 pso_num=26 pso_serial#=151 2025-07-26 15:51:25.911282 :B746CD57:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=4556 osp_idx=24 osp_ver=688906 osp_pg=0 (spawn #424976) 2025-07-26 15:51:25.911283 :B746CD58: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 15:51:25.911283 :B746CD59:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=138 time=2225861798 2025-07-26 15:51:25.911283 :B746CD5A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 15:51:25.911284 :B746CD5B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 15:51:25.911284 :B746CD5C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 15:51:25.911284 :B746CD5D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 15:51:25.911285 :B746CD5E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 15:51:25.911775 :B746CD5F: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 15:51:25.911822 :B746CD67: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 16:01:26.703392 :B747956B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 16:01:27.067559 :B74795BA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 16:01:27.062 2025-07-26 16:01:27.067561 :B74795BB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18596 pso_num=26 pso_serial#=152 2025-07-26 16:01:27.067696 :B74795BC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18596 osp_idx=24 osp_ver=688907 osp_pg=0 (spawn #425000) 2025-07-26 16:01:27.067696 :B74795BD: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 16:01:27.067697 :B74795BE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=139 time=2226462954 2025-07-26 16:01:27.067697 :B74795BF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 16:01:27.067697 :B74795C0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 16:01:27.067698 :B74795C1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 16:01:27.067698 :B74795C2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 16:01:27.067698 :B74795C3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 16:01:27.068192 :B74795C4: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 16:01:27.068242 :B74795CA: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 16:11:27.756579 :B74865FE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 16:11:28.105075 :B7486653:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 16:11:28.100 2025-07-26 16:11:28.105077 :B7486654:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18960 pso_num=26 pso_serial#=153 2025-07-26 16:11:28.105215 :B7486655:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18960 osp_idx=24 osp_ver=688908 osp_pg=0 (spawn #425024) 2025-07-26 16:11:28.105216 :B7486656: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 16:11:28.105216 :B7486657:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=140 time=2227064001 2025-07-26 16:11:28.105217 :B7486658:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 16:11:28.105217 :B7486659:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 16:11:28.105217 :B748665A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 16:11:28.105218 :B748665B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 16:11:28.105218 :B748665C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 16:11:28.105706 :B748665D: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 16:11:28.105756 :B7486667: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 16:14:23.055182 :B7489F50: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 16:21:29.275084 :B7492D44: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 16:31:29.998764 :B749F38E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 16:31:30.487773 :B749F3DC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 16:31:30.482 2025-07-26 16:31:30.487775 :B749F3DD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18848 pso_num=26 pso_serial#=154 2025-07-26 16:31:30.487912 :B749F3DE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18848 osp_idx=24 osp_ver=688909 osp_pg=0 (spawn #425068) 2025-07-26 16:31:30.487913 :B749F3DF: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 16:31:30.487914 :B749F3E0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=141 time=2228266376 2025-07-26 16:31:30.487915 :B749F3E1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 16:31:30.487916 :B749F3E2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 16:31:30.487916 :B749F3E3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 16:31:30.487916 :B749F3E4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 16:31:30.487917 :B749F3E5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 16:31:30.488421 :B749F3E6: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 16:31:30.488468 :B749F3ED: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 16:41:31.175845 :B74ABA26:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 16:41:31.550179 :B74ABA78:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 16:41:31.545 2025-07-26 16:41:31.550182 :B74ABA79:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9700 pso_num=26 pso_serial#=155 2025-07-26 16:41:31.550320 :B74ABA7A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9700 osp_idx=24 osp_ver=688910 osp_pg=0 (spawn #425091) 2025-07-26 16:41:31.550320 :B74ABA7B: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 16:41:31.550321 :B74ABA7C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=142 time=2228867439 2025-07-26 16:41:31.550321 :B74ABA7D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 16:41:31.550322 :B74ABA7E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 16:41:31.550322 :B74ABA7F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 16:41:31.550322 :B74ABA80:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 16:41:31.550323 :B74ABA81:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 16:41:31.550819 :B74ABA82: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 16:41:31.550864 :B74ABA8A: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 16:51:32.288014 :B74B810E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 16:51:32.777387 :B74B816E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 16:51:32.772 2025-07-26 16:51:32.777388 :B74B816F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16988 pso_num=26 pso_serial#=156 2025-07-26 16:51:32.777544 :B74B8170:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16988 osp_idx=24 osp_ver=688911 osp_pg=0 (spawn #425113) 2025-07-26 16:51:32.777544 :B74B8171: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 16:51:32.777545 :B74B8172:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=143 time=2229468673 2025-07-26 16:51:32.777545 :B74B8173:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 16:51:32.777546 :B74B8174:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 16:51:32.777546 :B74B8175:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 16:51:32.777547 :B74B8176:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 16:51:32.777547 :B74B8177:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 16:51:32.778073 :B74B8178: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 16:51:32.778125 :B74B817E: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 17:01:33.523234 :B74C498E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:01:33.692133 :B74C4991:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:01:33.680 2025-07-26 17:01:33.692135 :B74C4992:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11644 pso_num=26 pso_serial#=157 2025-07-26 17:01:33.692276 :B74C4993:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11644 (legacy spawn) 2025-07-26 17:01:33.698712 :B74C4994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-26 17:01:33.698882 :B74C4999:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-26 17:01:33.699685 :B74C499E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:01:33.840384 :B74C49ED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:01:33.836 2025-07-26 17:01:33.840386 :B74C49EE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17080 pso_num=26 pso_serial#=158 2025-07-26 17:01:33.840524 :B74C49EF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17080 osp_idx=24 osp_ver=688913 osp_pg=0 (spawn #425138) 2025-07-26 17:01:33.840524 :B74C49F0: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 17:01:33.840525 :B74C49F1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=144 time=2230069736 2025-07-26 17:01:33.840525 :B74C49F2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 17:01:33.840526 :B74C49F3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 17:01:33.840526 :B74C49F4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 17:01:33.840526 :B74C49F5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 17:01:33.840527 :B74C49F6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 17:01:33.841026 :B74C49F7: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 17:01:33.841070 :B74C49FF: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 17:11:34.518826 :B74D1953:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:11:34.961862 :B74D19B4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:11:34.956 2025-07-26 17:11:34.961864 :B74D19B5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6372 pso_num=26 pso_serial#=159 2025-07-26 17:11:34.962023 :B74D19B6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6372 osp_idx=24 osp_ver=688914 osp_pg=0 (spawn #425162) 2025-07-26 17:11:34.962024 :B74D19B7: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 17:11:34.962024 :B74D19B8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=145 time=2230670861 2025-07-26 17:11:34.962025 :B74D19B9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 17:11:34.962026 :B74D19BA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 17:11:34.962026 :B74D19BB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 17:11:34.962026 :B74D19BC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 17:11:34.962027 :B74D19BD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 17:11:34.962626 :B74D19BE: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 17:11:34.962703 :B74D19C8: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 17:21:35.835728 :B74DE096:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:21:36.121822 :B74DE0FB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:21:36.116 2025-07-26 17:21:36.121824 :B74DE0FC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16804 pso_num=26 pso_serial#=160 2025-07-26 17:21:36.121966 :B74DE0FD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16804 osp_idx=24 osp_ver=688915 osp_pg=0 (spawn #425184) 2025-07-26 17:21:36.121966 :B74DE0FE: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 17:21:36.121967 :B74DE0FF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=146 time=2231272017 2025-07-26 17:21:36.121967 :B74DE100:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 17:21:36.121968 :B74DE101:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 17:21:36.121968 :B74DE102:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 17:21:36.121968 :B74DE103:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 17:21:36.121968 :B74DE104:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 17:21:36.122483 :B74DE105: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 17:21:36.122530 :B74DE10C: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 17:31:36.955585 :B74EA785:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:31:37.303960 :B74EA7F4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:31:37.298 2025-07-26 17:31:37.303962 :B74EA7F5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13760 pso_num=26 pso_serial#=161 2025-07-26 17:31:37.304098 :B74EA7F6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13760 osp_idx=24 osp_ver=688916 osp_pg=0 (spawn #425207) 2025-07-26 17:31:37.304098 :B74EA7F7: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 17:31:37.304099 :B74EA7F8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=147 time=2231873204 2025-07-26 17:31:37.304099 :B74EA7F9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 17:31:37.304100 :B74EA7FA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 17:31:37.304100 :B74EA7FB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 17:31:37.304100 :B74EA7FC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 17:31:37.304101 :B74EA7FD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 17:31:37.304595 :B74EA7FE: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 17:31:37.304641 :B74EA805: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 17:41:38.097684 :B74F6E21:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:41:38.352447 :B74F6E95:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:41:38.347 2025-07-26 17:41:38.352448 :B74F6E96:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18784 pso_num=26 pso_serial#=162 2025-07-26 17:41:38.352592 :B74F6E97:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18784 osp_idx=24 osp_ver=688917 osp_pg=0 (spawn #425230) 2025-07-26 17:41:38.352592 :B74F6E98: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 17:41:38.352593 :B74F6E99:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=148 time=2232474251 2025-07-26 17:41:38.352593 :B74F6E9A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 17:41:38.352593 :B74F6E9B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 17:41:38.352593 :B74F6E9C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 17:41:38.352594 :B74F6E9D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 17:41:38.352594 :B74F6E9E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 17:41:38.353084 :B74F6E9F: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 17:41:38.353132 :B74F6EA6: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 17:51:39.154581 :B75034D9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 17:51:39.409416 :B7503523:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 17:51:39.404 2025-07-26 17:51:39.409418 :B7503524:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19244 pso_num=26 pso_serial#=163 2025-07-26 17:51:39.409551 :B7503525:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19244 osp_idx=24 osp_ver=688918 osp_pg=0 (spawn #425252) 2025-07-26 17:51:39.409552 :B7503526: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 17:51:39.409552 :B7503527:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=149 time=2233075298 2025-07-26 17:51:39.409552 :B7503528:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 17:51:39.409553 :B7503529:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 17:51:39.409553 :B750352A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 17:51:39.409553 :B750352B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 17:51:39.409554 :B750352C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 17:51:39.410042 :B750352D: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 17:51:39.410087 :B7503537: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 18:01:40.180377 :B750FD12:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-26 18:01:40.492038 :B750FD6A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-26 18:01:40.487 2025-07-26 18:01:40.492041 :B750FD6B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7568 pso_num=26 pso_serial#=164 2025-07-26 18:01:40.492185 :B750FD6C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7568 osp_idx=24 osp_ver=688919 osp_pg=0 (spawn #425277) 2025-07-26 18:01:40.492185 :B750FD6D: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 18:01:40.492186 :B750FD6E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=150 time=2233676392 2025-07-26 18:01:40.492186 :B750FD6F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-26 18:01:40.492186 :B750FD70:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-26 18:01:40.492187 :B750FD71:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-26 18:01:40.492187 :B750FD72:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-26 18:01:40.492187 :B750FD73:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-26 18:01:40.492693 :B750FD74: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 18:01:40.492741 :B750FD7B: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: 7568, W000)