Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250820080902\orcl_w000_21156_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:35343M/63366M, Ph+PgF:40544M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21156, image: ORACLE.EXE (W000) *** 2025-08-20 08:09:02.162 *** SESSION ID:(177.33611) 2025-08-20 08:09:02.162 *** 2025-08-20 08:09:02.162 Process diagnostic dump for ORACLE.EXE (W000), OS id=21156, pid: 22, proc_ser: 9, sid: 177, sess_ser: 33611 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=1.880776 sec, exc=1.880776 sec, total=1.880776 sec wait times: max=5.000000 sec, heur=4 min 37 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.009207 sec, exc=5.009207 sec, total=5.009207 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.007224 sec, exc=5.007224 sec, total=5.007224 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.001285 sec, exc=5.001285 sec, total=5.001285 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=52 seq_num=53 snap_id=1 wait times: snap=5.000327 sec, exc=5.000327 sec, total=5.000327 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=51 seq_num=52 snap_id=1 wait times: snap=5.011728 sec, exc=5.011728 sec, total=5.011728 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=5.008633 sec, exc=5.008633 sec, total=5.008633 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.014743 sec, exc=5.014743 sec, total=5.014743 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=48 seq_num=49 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 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=47 seq_num=48 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 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=5.015245 sec, exc=5.015245 sec, total=5.015245 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 177 serial 33611 --------------------------------------------------- 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:07:02 - 08:09:02] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-20 08:09:02.162 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21156, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-20 05:28:35.967048 :C21F6820:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-20 05:28:35.969369 :C21F6822:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-20 05:34:05.339141 :C21FD440:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-20 05:44:06.312928 :C2209A0A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 05:44:06.645722 :C2209A5A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 05:44:06.641 2025-08-20 05:44:06.645724 :C2209A5B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19972 pso_num=22 pso_serial#=251 2025-08-20 05:44:06.645863 :C2209A5C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19972 osp_idx=0 osp_ver=538628 osp_pg=0 (spawn #506896) 2025-08-20 05:44:06.645864 :C2209A5D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 05:44:06.645864 :C2209A5E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=215 time=54348658 2025-08-20 05:44:06.645865 :C2209A5F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 05:44:06.645865 :C2209A60:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 05:44:06.645866 :C2209A61:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 05:44:06.645866 :C2209A62:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 05:44:06.645867 :C2209A63:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 05:44:06.646360 :C2209A64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 05:44:06.646407 :C2209A6C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 05:54:07.449423 :C2215F70:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 05:54:07.876098 :C2215FE2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 05:54:07.871 2025-08-20 05:54:07.876101 :C2215FE3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20532 pso_num=22 pso_serial#=252 2025-08-20 05:54:07.876240 :C2215FE4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20532 osp_idx=0 osp_ver=538629 osp_pg=0 (spawn #506918) 2025-08-20 05:54:07.876241 :C2215FE5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 05:54:07.876241 :C2215FE6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=216 time=54949893 2025-08-20 05:54:07.876242 :C2215FE7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 05:54:07.876242 :C2215FE8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 05:54:07.876242 :C2215FE9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 05:54:07.876242 :C2215FEA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 05:54:07.876243 :C2215FEB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 05:54:07.876741 :C2215FEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 05:54:07.876786 :C2215FF5:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 06:04:08.864845 :C2222805:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 06:04:09.104151 :C2222875:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 06:04:09.099 2025-08-20 06:04:09.104152 :C2222876:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20688 pso_num=22 pso_serial#=253 2025-08-20 06:04:09.104288 :C2222877:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20688 osp_idx=0 osp_ver=538630 osp_pg=0 (spawn #506944) 2025-08-20 06:04:09.104288 :C2222878:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 06:04:09.104289 :C2222879:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=217 time=55551112 2025-08-20 06:04:09.104289 :C222287A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 06:04:09.104290 :C222287B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 06:04:09.104290 :C222287C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 06:04:09.104290 :C222287D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 06:04:09.104291 :C222287E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 06:04:09.104770 :C222287F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 06:04:09.104820 :C2222889:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 06:14:10.025733 :C222F2D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 06:14:10.514735 :C222F32A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 06:14:10.510 2025-08-20 06:14:10.514738 :C222F32B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20828 pso_num=22 pso_serial#=254 2025-08-20 06:14:10.514876 :C222F32C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20828 osp_idx=0 osp_ver=538631 osp_pg=0 (spawn #506967) 2025-08-20 06:14:10.514876 :C222F32D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 06:14:10.514877 :C222F32E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=218 time=56152518 2025-08-20 06:14:10.514877 :C222F32F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 06:14:10.514877 :C222F330:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 06:14:10.514878 :C222F331:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 06:14:10.514878 :C222F332:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 06:14:10.514878 :C222F333:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 06:14:10.515359 :C222F334:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 06:14:10.515407 :C222F33E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 06:24:11.540988 :C223BB5E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 06:24:11.811143 :C223BB9F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 06:24:11.806 2025-08-20 06:24:11.811146 :C223BBA0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20768 pso_num=22 pso_serial#=255 2025-08-20 06:24:11.811335 :C223BBA1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20768 osp_idx=0 osp_ver=538632 osp_pg=0 (spawn #506989) 2025-08-20 06:24:11.811336 :C223BBA2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 06:24:11.811337 :C223BBA3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=219 time=56753815 2025-08-20 06:24:11.811338 :C223BBA4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 06:24:11.811338 :C223BBA5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 06:24:11.811339 :C223BBA6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 06:24:11.811339 :C223BBA7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 06:24:11.811339 :C223BBA8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 06:24:11.811845 :C223BBA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 06:24:11.811889 :C223BBB1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 06:34:12.704575 :C2248107:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 06:34:13.084222 :C224816E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 06:34:13.079 2025-08-20 06:34:13.084225 :C224816F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19820 pso_num=22 pso_serial#=1 2025-08-20 06:34:13.084359 :C2248170:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19820 osp_idx=0 osp_ver=538633 osp_pg=0 (spawn #507012) 2025-08-20 06:34:13.084360 :C2248171:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 06:34:13.084360 :C2248172:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=220 time=57355096 2025-08-20 06:34:13.084361 :C2248173:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 06:34:13.084361 :C2248174:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 06:34:13.084362 :C2248175:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 06:34:13.084362 :C2248176:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 06:34:13.084362 :C2248177:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 06:34:13.084846 :C2248178:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 06:34:13.084890 :C224817F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 06:44:14.117429 :C22547DF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 06:44:14.356504 :C2254821:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 06:44:14.351 2025-08-20 06:44:14.356506 :C2254822:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22292 pso_num=22 pso_serial#=2 2025-08-20 06:44:14.356638 :C2254823:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22292 osp_idx=0 osp_ver=538634 osp_pg=0 (spawn #507035) 2025-08-20 06:44:14.356639 :C2254824:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 06:44:14.356639 :C2254825:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=221 time=57956362 2025-08-20 06:44:14.356640 :C2254826:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 06:44:14.356640 :C2254827:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 06:44:14.356641 :C2254828:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 06:44:14.356641 :C2254829:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 06:44:14.356641 :C225482A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 06:44:14.357123 :C225482B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 06:44:14.357168 :C2254832:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 06:54:15.319209 :C2260D5F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 06:54:15.667773 :C2260DC6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 06:54:15.663 2025-08-20 06:54:15.667775 :C2260DC7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21932 pso_num=22 pso_serial#=3 2025-08-20 06:54:15.667917 :C2260DC8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21932 osp_idx=0 osp_ver=538635 osp_pg=0 (spawn #507057) 2025-08-20 06:54:15.667918 :C2260DC9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 06:54:15.667918 :C2260DCA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=222 time=58557674 2025-08-20 06:54:15.667919 :C2260DCB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 06:54:15.667919 :C2260DCC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 06:54:15.667919 :C2260DCD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 06:54:15.667920 :C2260DCE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 06:54:15.667920 :C2260DCF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 06:54:15.668396 :C2260DD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 06:54:15.668449 :C2260DD6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 07:04:16.659126 :C226D512:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 07:04:17.023190 :C226D56F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 07:04:17.018 2025-08-20 07:04:17.023192 :C226D570:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16340 pso_num=22 pso_serial#=4 2025-08-20 07:04:17.023327 :C226D571:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16340 osp_idx=0 osp_ver=538636 osp_pg=0 (spawn #507083) 2025-08-20 07:04:17.023328 :C226D572:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 07:04:17.023328 :C226D573:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=223 time=59159033 2025-08-20 07:04:17.023329 :C226D574:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 07:04:17.023329 :C226D575:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 07:04:17.023330 :C226D576:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 07:04:17.023330 :C226D577:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 07:04:17.023331 :C226D578:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 07:04:17.023815 :C226D579:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 07:04:17.023858 :C226D582:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 07:14:17.886380 :C227A3BB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 07:14:18.291929 :C227A41E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 07:14:18.287 2025-08-20 07:14:18.291931 :C227A41F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17264 pso_num=22 pso_serial#=5 2025-08-20 07:14:18.292070 :C227A420:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17264 osp_idx=0 osp_ver=538637 osp_pg=0 (spawn #507106) 2025-08-20 07:14:18.292070 :C227A421:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 07:14:18.292071 :C227A422:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=59760299 2025-08-20 07:14:18.292071 :C227A423:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 07:14:18.292072 :C227A424:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 07:14:18.292072 :C227A425:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 07:14:18.292073 :C227A426:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 07:14:18.292073 :C227A427:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 07:14:18.292557 :C227A428:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 07:14:18.292606 :C227A42F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 07:24:19.127757 :C2286981:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 07:24:19.663842 :C22869FB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 07:24:19.659 2025-08-20 07:24:19.663844 :C22869FC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21940 pso_num=22 pso_serial#=6 2025-08-20 07:24:19.663982 :C22869FD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21940 osp_idx=0 osp_ver=538638 osp_pg=0 (spawn #507128) 2025-08-20 07:24:19.663983 :C22869FE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 07:24:19.663983 :C22869FF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=60361674 2025-08-20 07:24:19.663984 :C2286A00:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 07:24:19.663984 :C2286A01:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 07:24:19.663984 :C2286A02:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 07:24:19.663985 :C2286A03:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 07:24:19.663986 :C2286A04:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 07:24:19.664485 :C2286A05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 07:24:19.664528 :C2286A0D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 07:28:42.602391 :C228BF83:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-20 07:34:20.977793 :C229301B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-20 07:44:21.953262 :C229F679:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 07:44:22.301765 :C229F6D5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 07:44:22.297 2025-08-20 07:44:22.301767 :C229F6D6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19984 pso_num=22 pso_serial#=7 2025-08-20 07:44:22.301914 :C229F6D7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19984 osp_idx=0 osp_ver=538639 osp_pg=0 (spawn #507173) 2025-08-20 07:44:22.301915 :C229F6D8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 07:44:22.301915 :C229F6D9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=226 time=61564315 2025-08-20 07:44:22.301916 :C229F6DA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 07:44:22.301916 :C229F6DB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 07:44:22.301916 :C229F6DC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 07:44:22.301917 :C229F6DD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 07:44:22.301917 :C229F6DE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 07:44:22.302423 :C229F6DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 07:44:22.302466 :C229F6E7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 07:54:23.203593 :C22ABBC9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 07:54:23.691038 :C22ABC28:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 07:54:23.686 2025-08-20 07:54:23.691040 :C22ABC29:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11728 pso_num=22 pso_serial#=8 2025-08-20 07:54:23.691182 :C22ABC2A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11728 osp_idx=0 osp_ver=538640 osp_pg=0 (spawn #507195) 2025-08-20 07:54:23.691183 :C22ABC2B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 07:54:23.691183 :C22ABC2C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=227 time=62165705 2025-08-20 07:54:23.691184 :C22ABC2D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 07:54:23.691184 :C22ABC2E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 07:54:23.691184 :C22ABC2F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 07:54:23.691185 :C22ABC30:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 07:54:23.691185 :C22ABC31:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 07:54:23.691676 :C22ABC32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 07:54:23.691721 :C22ABC3A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-20 08:04:24.564935 :C22B8334:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-20 08:04:24.929137 :C22B839B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 08:04:24.924 2025-08-20 08:04:24.929139 :C22B839C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21156 pso_num=22 pso_serial#=9 2025-08-20 08:04:24.929275 :C22B839D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21156 osp_idx=0 osp_ver=538641 osp_pg=0 (spawn #507221) 2025-08-20 08:04:24.929276 :C22B839E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 08:04:24.929276 :C22B839F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=228 time=62766940 2025-08-20 08:04:24.929276 :C22B83A0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 08:04:24.929277 :C22B83A1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 08:04:24.929277 :C22B83A2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 08:04:24.929277 :C22B83A3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 08:04:24.929277 :C22B83A4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 08:04:24.929781 :C22B83A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 08:04:24.929829 :C22B83AC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 21156, W000)