Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250829220008\orcl_w000_20236_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:35101M/63366M, Ph+PgF:40076M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 20236, image: ORACLE.EXE (W000) *** 2025-08-29 22:00:08.150 *** SESSION ID:(177.7807) 2025-08-29 22:00:08.150 *** 2025-08-29 22:00:08.150 Process diagnostic dump for ORACLE.EXE (W000), OS id=20236, pid: 22, proc_ser: 135, sid: 177, sess_ser: 7807 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=2.336681 sec, exc=2.336681 sec, total=2.336681 sec wait times: max=5.000000 sec, heur=5 min 47 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.000030 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.000291 sec, exc=5.000291 sec, total=5.000291 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.014620 sec, exc=5.014620 sec, total=5.014620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.001028 sec, exc=5.001028 sec, total=5.001028 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=66 seq_num=67 snap_id=1 wait times: snap=5.009221 sec, exc=5.009221 sec, total=5.009221 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=65 seq_num=66 snap_id=1 wait times: snap=5.004638 sec, exc=5.004638 sec, total=5.004638 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=64 seq_num=65 snap_id=1 wait times: snap=5.015238 sec, exc=5.015238 sec, total=5.015238 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=63 seq_num=64 snap_id=1 wait times: snap=5.003228 sec, exc=5.003228 sec, total=5.003228 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=5.000607 sec, exc=5.000607 sec, total=5.000607 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.000770 sec, exc=5.000770 sec, total=5.000770 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=60 seq_num=61 snap_id=1 wait times: snap=5.003055 sec, exc=5.003055 sec, total=5.003055 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 177 serial 7807 --------------------------------------------------- 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, 21:58:08 - 22:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-29 22:00:08.150 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 20236, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-29 19:24:01.577704 :C6544193:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 19:24:01.577704 :C6544194:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 19:24:01.578199 :C6544195: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-29 19:24:01.578247 :C654419E: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-29 19:34:02.478622 :C65509CA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 19:34:02.733393 :C6550A29:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 19:34:02.728 2025-08-29 19:34:02.733395 :C6550A2A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7692 pso_num=22 pso_serial#=122 2025-08-29 19:34:02.733535 :C6550A2B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7692 osp_idx=0 osp_ver=555584 osp_pg=0 (spawn #538802) 2025-08-29 19:34:02.733536 :C6550A2C: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-29 19:34:02.733536 :C6550A2D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=881694033 2025-08-29 19:34:02.733537 :C6550A2E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 19:34:02.733537 :C6550A2F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 19:34:02.733539 :C6550A30:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 19:34:02.733539 :C6550A31:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 19:34:02.733540 :C6550A32:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 19:34:02.734043 :C6550A33: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-29 19:34:02.734087 :C6550A3A: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-29 19:44:03.586003 :C655D331:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 19:44:04.012787 :C655D395:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 19:44:04.008 2025-08-29 19:44:04.012789 :C655D396:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19972 pso_num=22 pso_serial#=123 2025-08-29 19:44:04.012922 :C655D397:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19972 osp_idx=0 osp_ver=555585 osp_pg=0 (spawn #538826) 2025-08-29 19:44:04.012923 :C655D398: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-29 19:44:04.012923 :C655D399:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=882295315 2025-08-29 19:44:04.012924 :C655D39A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 19:44:04.012924 :C655D39B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 19:44:04.012925 :C655D39C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 19:44:04.012925 :C655D39D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 19:44:04.012926 :C655D39E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 19:44:04.013421 :C655D39F: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-29 19:44:04.013466 :C655D3A8: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-29 19:54:04.868301 :C6569BD3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 19:54:05.232310 :C6569C44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 19:54:05.227 2025-08-29 19:54:05.232313 :C6569C45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23000 pso_num=22 pso_serial#=124 2025-08-29 19:54:05.232445 :C6569C46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23000 osp_idx=0 osp_ver=555586 osp_pg=0 (spawn #538848) 2025-08-29 19:54:05.232446 :C6569C47: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-29 19:54:05.232447 :C6569C48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=67 time=882896533 2025-08-29 19:54:05.232448 :C6569C49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 19:54:05.232448 :C6569C4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 19:54:05.232449 :C6569C4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 19:54:05.232449 :C6569C4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 19:54:05.232450 :C6569C4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 19:54:05.232950 :C6569C4E: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-29 19:54:05.232997 :C6569C58: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-29 20:04:06.241460 :C6576754:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 20:04:06.605399 :C6576795:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 20:04:06.601 2025-08-29 20:04:06.605402 :C6576796:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16692 pso_num=22 pso_serial#=125 2025-08-29 20:04:06.605539 :C6576797:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16692 osp_idx=0 osp_ver=555587 osp_pg=0 (spawn #538874) 2025-08-29 20:04:06.605540 :C6576798: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-29 20:04:06.605540 :C6576799:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=68 time=883497908 2025-08-29 20:04:06.605541 :C657679A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 20:04:06.605541 :C657679B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 20:04:06.605542 :C657679C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 20:04:06.605542 :C657679D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 20:04:06.605542 :C657679E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 20:04:06.606050 :C657679F: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-29 20:04:06.606095 :C65767A7: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-29 20:14:07.639644 :C6583776:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 20:14:08.050566 :C65837B9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 20:14:08.046 2025-08-29 20:14:08.050568 :C65837BA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4160 pso_num=22 pso_serial#=126 2025-08-29 20:14:08.050711 :C65837BB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4160 osp_idx=0 osp_ver=555588 osp_pg=0 (spawn #538897) 2025-08-29 20:14:08.050712 :C65837BC: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-29 20:14:08.050712 :C65837BD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=69 time=884099362 2025-08-29 20:14:08.050713 :C65837BE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 20:14:08.050713 :C65837BF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 20:14:08.050713 :C65837C0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 20:14:08.050713 :C65837C1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 20:14:08.050714 :C65837C2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 20:14:08.051212 :C65837C3: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-29 20:14:08.051260 :C65837CA: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-29 20:24:08.889757 :C65902A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 20:24:09.191368 :C65902DE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 20:24:09.186 2025-08-29 20:24:09.191371 :C65902DF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12488 pso_num=22 pso_serial#=127 2025-08-29 20:24:09.191516 :C65902E0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12488 osp_idx=0 osp_ver=555589 osp_pg=0 (spawn #538919) 2025-08-29 20:24:09.191516 :C65902E1: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-29 20:24:09.191517 :C65902E2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=70 time=884700502 2025-08-29 20:24:09.191517 :C65902E3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 20:24:09.191517 :C65902E4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 20:24:09.191518 :C65902E5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 20:24:09.191518 :C65902E6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 20:24:09.191518 :C65902E7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 20:24:09.192017 :C65902E8: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-29 20:24:09.192061 :C65902F0: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-29 20:34:09.947857 :C659CB1E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 20:34:10.374536 :C659CB6B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 20:34:10.370 2025-08-29 20:34:10.374538 :C659CB6C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19264 pso_num=22 pso_serial#=128 2025-08-29 20:34:10.374680 :C659CB6D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19264 osp_idx=0 osp_ver=555590 osp_pg=0 (spawn #538942) 2025-08-29 20:34:10.374681 :C659CB6E: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-29 20:34:10.374681 :C659CB6F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=71 time=885301674 2025-08-29 20:34:10.374682 :C659CB70:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 20:34:10.374682 :C659CB71:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 20:34:10.374682 :C659CB72:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 20:34:10.374683 :C659CB73:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 20:34:10.374683 :C659CB74:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 20:34:10.375202 :C659CB75: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-29 20:34:10.375247 :C659CB7C: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-29 20:44:11.226120 :C65A940B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 20:44:11.590269 :C65A944C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 20:44:11.585 2025-08-29 20:44:11.590272 :C65A944D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22076 pso_num=22 pso_serial#=129 2025-08-29 20:44:11.590418 :C65A944E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22076 osp_idx=0 osp_ver=555591 osp_pg=0 (spawn #538965) 2025-08-29 20:44:11.590419 :C65A944F: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-29 20:44:11.590419 :C65A9450:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=72 time=885902893 2025-08-29 20:44:11.590420 :C65A9451:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 20:44:11.590420 :C65A9452:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 20:44:11.590420 :C65A9453:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 20:44:11.590421 :C65A9454:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 20:44:11.590421 :C65A9455:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 20:44:11.590946 :C65A9456: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-29 20:44:11.590992 :C65A945E: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-29 20:45:16.449494 :C65AA9E2: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-29 20:54:12.863384 :C65B5D05: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-29 21:04:13.675553 :C65C27C5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 21:04:14.039566 :C65C2803:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 21:04:14.034 2025-08-29 21:04:14.039568 :C65C2804:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22788 pso_num=22 pso_serial#=130 2025-08-29 21:04:14.039703 :C65C2805:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22788 osp_idx=0 osp_ver=555592 osp_pg=0 (spawn #539013) 2025-08-29 21:04:14.039704 :C65C2806: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-29 21:04:14.039704 :C65C2807:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=73 time=887105346 2025-08-29 21:04:14.039704 :C65C2808:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 21:04:14.039704 :C65C2809:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 21:04:14.039705 :C65C280A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 21:04:14.039705 :C65C280B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 21:04:14.039705 :C65C280C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 21:04:14.040196 :C65C280D: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-29 21:04:14.040243 :C65C2814: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-29 21:14:14.894880 :C65CF557:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 21:14:15.259080 :C65CF59C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 21:14:15.254 2025-08-29 21:14:15.259083 :C65CF59D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4428 pso_num=22 pso_serial#=131 2025-08-29 21:14:15.259223 :C65CF59E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4428 osp_idx=0 osp_ver=555593 osp_pg=0 (spawn #539036) 2025-08-29 21:14:15.259224 :C65CF59F: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-29 21:14:15.259224 :C65CF5A0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=74 time=887706565 2025-08-29 21:14:15.259225 :C65CF5A1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 21:14:15.259225 :C65CF5A2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 21:14:15.259225 :C65CF5A3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 21:14:15.259226 :C65CF5A4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 21:14:15.259226 :C65CF5A5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 21:14:15.259732 :C65CF5A6: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-29 21:14:15.259778 :C65CF5AE: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-29 21:24:16.038956 :C65DBEB7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 21:24:16.543816 :C65DBEF5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 21:24:16.539 2025-08-29 21:24:16.543819 :C65DBEF6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22412 pso_num=22 pso_serial#=132 2025-08-29 21:24:16.543957 :C65DBEF7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22412 osp_idx=0 osp_ver=555594 osp_pg=0 (spawn #539058) 2025-08-29 21:24:16.543958 :C65DBEF8: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-29 21:24:16.543958 :C65DBEF9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=75 time=888307846 2025-08-29 21:24:16.543959 :C65DBEFA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 21:24:16.543959 :C65DBEFB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 21:24:16.543959 :C65DBEFC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 21:24:16.543960 :C65DBEFD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 21:24:16.543960 :C65DBEFE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 21:24:16.544467 :C65DBEFF: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-29 21:24:16.544511 :C65DBF07: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-29 21:34:17.287957 :C65E858A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 21:34:17.818255 :C65E85D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 21:34:17.813 2025-08-29 21:34:17.818258 :C65E85D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21768 pso_num=22 pso_serial#=133 2025-08-29 21:34:17.818392 :C65E85D2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21768 osp_idx=0 osp_ver=555595 osp_pg=0 (spawn #539081) 2025-08-29 21:34:17.818393 :C65E85D3: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-29 21:34:17.818394 :C65E85D4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=76 time=888909127 2025-08-29 21:34:17.818394 :C65E85D5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 21:34:17.818394 :C65E85D6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 21:34:17.818395 :C65E85D7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 21:34:17.818395 :C65E85D8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 21:34:17.818395 :C65E85D9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 21:34:17.818957 :C65E85DA: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-29 21:34:17.819001 :C65E85E2: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-29 21:44:18.581594 :C65F4BF3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 21:44:19.127697 :C65F4C40:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 21:44:19.122 2025-08-29 21:44:19.127699 :C65F4C41:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22220 pso_num=22 pso_serial#=134 2025-08-29 21:44:19.127835 :C65F4C42:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22220 osp_idx=0 osp_ver=555596 osp_pg=0 (spawn #539104) 2025-08-29 21:44:19.127836 :C65F4C43: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-29 21:44:19.127837 :C65F4C44:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=77 time=889510424 2025-08-29 21:44:19.127837 :C65F4C45:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 21:44:19.127837 :C65F4C46:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 21:44:19.127838 :C65F4C47:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 21:44:19.127838 :C65F4C48:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 21:44:19.127839 :C65F4C49:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 21:44:19.128329 :C65F4C4A: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-29 21:44:19.128381 :C65F4C53: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-29 21:54:19.854424 :C660128B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-29 21:54:20.359746 :C66012D9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-29 21:54:20.354 2025-08-29 21:54:20.359748 :C66012DA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20236 pso_num=22 pso_serial#=135 2025-08-29 21:54:20.359971 :C66012DB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20236 osp_idx=0 osp_ver=555597 osp_pg=0 (spawn #539126) 2025-08-29 21:54:20.359972 :C66012DC: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-29 21:54:20.359972 :C66012DD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=78 time=890111658 2025-08-29 21:54:20.359973 :C66012DE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-29 21:54:20.359974 :C66012DF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-29 21:54:20.359974 :C66012E0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-29 21:54:20.359975 :C66012E1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-29 21:54:20.359975 :C66012E2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-29 21:54:20.360637 :C66012E3: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-29 21:54:20.360692 :C66012E9: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: 20236, W000)