Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817180126\orcl_w000_21088_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:35393M/63366M, Ph+PgF:40661M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21088, image: ORACLE.EXE (W000) *** 2025-08-17 18:01:26.950 *** SESSION ID:(177.8103) 2025-08-17 18:01:26.950 *** 2025-08-17 18:01:26.950 Process diagnostic dump for ORACLE.EXE (W000), OS id=21088, pid: 22, proc_ser: 147, sid: 177, sess_ser: 8103 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=1.567931 sec, exc=1.567931 sec, total=1.567931 sec wait times: max=5.000000 sec, heur=5 min 7 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.000035 sec since current wait 0: 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.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 1: 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.004617 sec, exc=5.004617 sec, total=5.004617 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=5.004621 sec, exc=5.004621 sec, total=5.004621 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=5.008292 sec, exc=5.008292 sec, total=5.008292 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000041 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.000156 sec, exc=5.000156 sec, total=5.000156 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.000574 sec, exc=5.000574 sec, total=5.000574 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 6: 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.007143 sec, exc=5.007143 sec, total=5.007143 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: 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.004644 sec, exc=5.004644 sec, total=5.004644 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: 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.004636 sec, exc=5.004636 sec, total=5.004636 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=52 seq_num=53 snap_id=1 wait times: snap=5.009199 sec, exc=5.009199 sec, total=5.009199 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 8103 --------------------------------------------------- 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, 17:59:26 - 18:01:26] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-17 18:01:26.950 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21088, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 15:45:52.893294 :C0FE88D4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22256 pso_num=22 pso_serial#=133 2025-08-17 15:45:52.893436 :C0FE88D5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22256 osp_idx=0 osp_ver=527800 osp_pg=0 (spawn #498287) 2025-08-17 15:45:52.893437 :C0FE88D6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 15:45:52.893437 :C0FE88D7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=244 time=4126222204 2025-08-17 15:45:52.893437 :C0FE88D8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 15:45:52.893438 :C0FE88D9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 15:45:52.893438 :C0FE88DA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 15:45:52.893439 :C0FE88DB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 15:45:52.893439 :C0FE88DC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 15:45:52.893936 :C0FE88DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 15:45:52.893984 :C0FE88E3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-17 15:45:52.913739 :C0FE8933:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 15:46:02.533100 :C0FE8C77:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 15:46:02.527 2025-08-17 15:46:02.533102 :C0FE8C78:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20352 pso_num=22 pso_serial#=134 2025-08-17 15:46:02.533252 :C0FE8C79:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20352 osp_idx=0 osp_ver=527801 osp_pg=0 (spawn #498290) 2025-08-17 15:46:02.533253 :C0FE8C7A: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-17 15:46:02.533253 :C0FE8C7B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=245 time=4126231845 2025-08-17 15:46:02.533254 :C0FE8C7C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 15:46:02.533255 :C0FE8C7D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 15:46:02.533255 :C0FE8C7E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 15:46:02.533255 :C0FE8C7F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 15:46:02.533256 :C0FE8C80:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 15:46:02.533815 :C0FE8C81: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-17 15:46:02.533886 :C0FE8C8B: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-17 15:56:03.388675 :C0FF5148:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 15:56:03.810167 :C0FF51AC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 15:56:03.805 2025-08-17 15:56:03.810169 :C0FF51AD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20052 pso_num=22 pso_serial#=135 2025-08-17 15:56:03.810314 :C0FF51AE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20052 osp_idx=0 osp_ver=527802 osp_pg=0 (spawn #498312) 2025-08-17 15:56:03.810315 :C0FF51AF: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-17 15:56:03.810315 :C0FF51B0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=246 time=4126833111 2025-08-17 15:56:03.810316 :C0FF51B1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 15:56:03.810316 :C0FF51B2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 15:56:03.810317 :C0FF51B3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 15:56:03.810317 :C0FF51B4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 15:56:03.810317 :C0FF51B5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 15:56:03.810821 :C0FF51B6: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-17 15:56:03.810867 :C0FF51BE: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-17 16:06:04.724082 :C1001FC6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 16:06:05.088293 :C1002032:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 16:06:05.083 2025-08-17 16:06:05.088295 :C1002033:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6008 pso_num=22 pso_serial#=136 2025-08-17 16:06:05.088435 :C1002034:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6008 osp_idx=0 osp_ver=527803 osp_pg=0 (spawn #498339) 2025-08-17 16:06:05.088436 :C1002035: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-17 16:06:05.088436 :C1002036:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=247 time=4127434392 2025-08-17 16:06:05.088437 :C1002037:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 16:06:05.088437 :C1002038:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 16:06:05.088437 :C1002039:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 16:06:05.088438 :C100203A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 16:06:05.088438 :C100203B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 16:06:05.088944 :C100203C: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-17 16:06:05.088987 :C1002043: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-17 16:16:06.106047 :C100E813:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 16:16:06.407672 :C100E86A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 16:16:06.402 2025-08-17 16:16:06.407674 :C100E86B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12684 pso_num=22 pso_serial#=137 2025-08-17 16:16:06.407808 :C100E86C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12684 osp_idx=0 osp_ver=527804 osp_pg=0 (spawn #498362) 2025-08-17 16:16:06.407809 :C100E86D: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-17 16:16:06.407809 :C100E86E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=248 time=4128035720 2025-08-17 16:16:06.407810 :C100E86F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 16:16:06.407810 :C100E870:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 16:16:06.407810 :C100E871:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 16:16:06.407812 :C100E872:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 16:16:06.407812 :C100E873:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 16:16:06.408296 :C100E874: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-17 16:16:06.408341 :C100E87B: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-17 16:26:07.374606 :C101ACC3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 16:26:07.785922 :C101AD2F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 16:26:07.781 2025-08-17 16:26:07.785924 :C101AD30:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22444 pso_num=22 pso_serial#=138 2025-08-17 16:26:07.786158 :C101AD31:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22444 osp_idx=0 osp_ver=527805 osp_pg=0 (spawn #498384) 2025-08-17 16:26:07.786159 :C101AD32: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-17 16:26:07.786160 :C101AD33:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=249 time=4128637095 2025-08-17 16:26:07.786160 :C101AD34:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 16:26:07.786161 :C101AD35:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 16:26:07.786161 :C101AD36:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 16:26:07.786162 :C101AD37:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 16:26:07.786162 :C101AD38:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 16:26:07.786706 :C101AD39: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-17 16:26:07.786754 :C101AD41: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-17 16:26:31.598691 :C101B495: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-17 16:36:09.070609 :C1027238: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-17 16:46:09.903260 :C10336A4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 16:46:10.376803 :C1033708:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 16:46:10.372 2025-08-17 16:46:10.376805 :C1033709:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20572 pso_num=22 pso_serial#=139 2025-08-17 16:46:10.376948 :C103370A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20572 osp_idx=0 osp_ver=527806 osp_pg=0 (spawn #498429) 2025-08-17 16:46:10.376948 :C103370B: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-17 16:46:10.376949 :C103370C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=250 time=4129839689 2025-08-17 16:46:10.376949 :C103370D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 16:46:10.376950 :C103370E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 16:46:10.376950 :C103370F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 16:46:10.376950 :C1033710:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 16:46:10.376951 :C1033711:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 16:46:10.377480 :C1033712: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-17 16:46:10.377525 :C103371A: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-17 16:56:11.259496 :C103FC52:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 16:56:11.717234 :C103FCA6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 16:56:11.713 2025-08-17 16:56:11.717237 :C103FCA7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21424 pso_num=22 pso_serial#=140 2025-08-17 16:56:11.717375 :C103FCA8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21424 osp_idx=0 osp_ver=527807 osp_pg=0 (spawn #498451) 2025-08-17 16:56:11.717376 :C103FCA9: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-17 16:56:11.717376 :C103FCAA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=251 time=4130441017 2025-08-17 16:56:11.717377 :C103FCAB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 16:56:11.717377 :C103FCAC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 16:56:11.717377 :C103FCAD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 16:56:11.717378 :C103FCAE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 16:56:11.717378 :C103FCAF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 16:56:11.717872 :C103FCB0: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-17 16:56:11.717916 :C103FCB7: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-17 17:06:12.669840 :C104CB9D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:06:13.034032 :C104CBF8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:06:13.029 2025-08-17 17:06:13.034035 :C104CBF9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19932 pso_num=22 pso_serial#=141 2025-08-17 17:06:13.034179 :C104CBFA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19932 osp_idx=0 osp_ver=527808 osp_pg=0 (spawn #498477) 2025-08-17 17:06:13.034179 :C104CBFB: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-17 17:06:13.034180 :C104CBFC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=252 time=4131042345 2025-08-17 17:06:13.034180 :C104CBFD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 17:06:13.034181 :C104CBFE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 17:06:13.034181 :C104CBFF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 17:06:13.034181 :C104CC00:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 17:06:13.034182 :C104CC01:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 17:06:13.034687 :C104CC02: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-17 17:06:13.034734 :C104CC09: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-17 17:16:14.021319 :C10592FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:16:14.463586 :C1059349:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:16:14.458 2025-08-17 17:16:14.463588 :C105934A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14012 pso_num=22 pso_serial#=142 2025-08-17 17:16:14.463734 :C105934B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14012 osp_idx=0 osp_ver=527809 osp_pg=0 (spawn #498500) 2025-08-17 17:16:14.463735 :C105934C: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-17 17:16:14.463735 :C105934D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=4131643767 2025-08-17 17:16:14.463736 :C105934E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 17:16:14.463736 :C105934F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 17:16:14.463736 :C1059350:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 17:16:14.463737 :C1059351:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 17:16:14.463737 :C1059352:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 17:16:14.464270 :C1059353: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-17 17:16:14.464315 :C105935A: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-17 17:26:15.380129 :C1065AE3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:26:15.853517 :C1065B3F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:26:15.848 2025-08-17 17:26:15.853519 :C1065B40:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19552 pso_num=22 pso_serial#=143 2025-08-17 17:26:15.853658 :C1065B41:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19552 osp_idx=0 osp_ver=527810 osp_pg=0 (spawn #498522) 2025-08-17 17:26:15.853658 :C1065B42: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-17 17:26:15.853659 :C1065B43:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=4132245158 2025-08-17 17:26:15.853659 :C1065B44:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 17:26:15.853659 :C1065B45:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 17:26:15.853660 :C1065B46:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 17:26:15.853660 :C1065B47:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 17:26:15.853660 :C1065B48:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 17:26:15.854163 :C1065B49: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-17 17:26:15.854211 :C1065B50: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-17 17:36:16.671196 :C1072225:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:36:17.269479 :C1072298:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:36:17.264 2025-08-17 17:36:17.269481 :C1072299:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14192 pso_num=22 pso_serial#=144 2025-08-17 17:36:17.269629 :C107229A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14192 osp_idx=0 osp_ver=527811 osp_pg=0 (spawn #498545) 2025-08-17 17:36:17.269630 :C107229B: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-17 17:36:17.269631 :C107229C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=4132846579 2025-08-17 17:36:17.269631 :C107229D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 17:36:17.269632 :C107229E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 17:36:17.269632 :C107229F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 17:36:17.269632 :C10722A0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 17:36:17.269633 :C10722A1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 17:36:17.270152 :C10722A2: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-17 17:36:17.270201 :C10722A9: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-17 17:46:18.155439 :C107E9BD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:46:18.597833 :C107EA19:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:46:18.598 2025-08-17 17:46:18.597836 :C107EA1A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21532 pso_num=22 pso_serial#=145 2025-08-17 17:46:18.597969 :C107EA1B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21532 osp_idx=0 osp_ver=527812 osp_pg=0 (spawn #498568) 2025-08-17 17:46:18.597969 :C107EA1C: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-17 17:46:18.597970 :C107EA1D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=4133447908 2025-08-17 17:46:18.597970 :C107EA1E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 17:46:18.597970 :C107EA1F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 17:46:18.597971 :C107EA20:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 17:46:18.597971 :C107EA21:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 17:46:18.597972 :C107EA22:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 17:46:18.598458 :C107EA23: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-17 17:46:18.598507 :C107EA2C: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-17 17:56:19.556625 :C108B25A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:56:19.908579 :C108B25D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:56:19.900 2025-08-17 17:56:19.908581 :C108B25E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20876 pso_num=22 pso_serial#=146 2025-08-17 17:56:19.908718 :C108B25F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20876 (legacy spawn) 2025-08-17 17:56:19.915116 :C108B260:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-17 17:56:19.915284 :C108B265:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-17 17:56:19.916102 :C108B2A6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 17:56:19.936359 :C108B2C0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 17:56:19.931 2025-08-17 17:56:19.936361 :C108B2C1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21088 pso_num=22 pso_serial#=147 2025-08-17 17:56:19.936499 :C108B2C2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21088 osp_idx=0 osp_ver=527814 osp_pg=0 (spawn #498590) 2025-08-17 17:56:19.936499 :C108B2C3: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-17 17:56:19.936500 :C108B2C4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=4134049236 2025-08-17 17:56:19.936500 :C108B2C5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 17:56:19.936500 :C108B2C6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 17:56:19.936501 :C108B2C7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 17:56:19.936501 :C108B2C8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 17:56:19.936501 :C108B2C9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 17:56:19.936991 :C108B2CA: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-17 17:56:19.937037 :C108B2D1: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: 21088, W000)