Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250621140608\orcl_w000_16092_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:36401M/63366M, Ph+PgF:42789M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16092, image: ORACLE.EXE (W000) *** 2025-06-21 14:06:08.134 *** SESSION ID:(201.35909) 2025-06-21 14:06:08.134 *** 2025-06-21 14:06:08.134 Process diagnostic dump for ORACLE.EXE (W000), OS id=16092, pid: 25, proc_ser: 133, sid: 201, sess_ser: 35909 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.611194 sec, exc=0.611194 sec, total=0.611194 sec wait times: max=5.000000 sec, heur=2 min 45 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.010162 sec, exc=5.010162 sec, total=5.010162 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.007167 sec, exc=5.007167 sec, total=5.007167 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.003203 sec, exc=5.003203 sec, total=5.003203 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=30 seq_num=31 snap_id=1 wait times: snap=5.006112 sec, exc=5.006112 sec, total=5.006112 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.003482 sec, exc=5.003482 sec, total=5.003482 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.014742 sec, exc=5.014742 sec, total=5.014742 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.010150 sec, exc=5.010150 sec, total=5.010150 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=26 seq_num=27 snap_id=1 wait times: snap=5.002636 sec, exc=5.002636 sec, total=5.002636 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.007242 sec, exc=5.007242 sec, total=5.007242 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.001240 sec, exc=5.001240 sec, total=5.001240 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 201 serial 35909 --------------------------------------------------- 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, 14:04:08 - 14:06:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-21 14:06:08.134 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16092, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-21 11:33:02.909346 :A7A53012:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 11:33:02.909346 :A7A53013:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 11:33:02.909868 :A7A53014:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 11:33:02.909919 :A7A5301B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 11:43:03.828150 :A7A5F2BB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 11:43:04.161378 :A7A5F323:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 11:43:04.156 2025-06-21 11:43:04.161380 :A7A5F324:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16220 pso_num=25 pso_serial#=120 2025-06-21 11:43:04.161511 :A7A5F325:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16220 osp_idx=23 osp_ver=163687 osp_pg=0 (spawn #307731) 2025-06-21 11:43:04.161511 :A7A5F326:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 11:43:04.161512 :A7A5F327:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=175 time=3481927344 2025-06-21 11:43:04.161512 :A7A5F328:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 11:43:04.161512 :A7A5F329:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 11:43:04.161513 :A7A5F32A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 11:43:04.161513 :A7A5F32B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 11:43:04.161513 :A7A5F32C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 11:43:04.162006 :A7A5F32D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 11:43:04.162050 :A7A5F335:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 11:53:05.170945 :A7A6B58B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 11:53:05.410027 :A7A6B5C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 11:53:05.405 2025-06-21 11:53:05.410029 :A7A6B5C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18272 pso_num=25 pso_serial#=121 2025-06-21 11:53:05.410172 :A7A6B5C7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18272 osp_idx=23 osp_ver=163688 osp_pg=0 (spawn #307754) 2025-06-21 11:53:05.410173 :A7A6B5C8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 11:53:05.410175 :A7A6B5C9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=176 time=3482528594 2025-06-21 11:53:05.410175 :A7A6B5CA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 11:53:05.410176 :A7A6B5CB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 11:53:05.410176 :A7A6B5CC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 11:53:05.410176 :A7A6B5CD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 11:53:05.410177 :A7A6B5CE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 11:53:05.410667 :A7A6B5CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 11:53:05.410711 :A7A6B5D5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 12:03:06.324962 :A7A77B48:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 12:03:06.627804 :A7A77BB6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 12:03:06.622 2025-06-21 12:03:06.627806 :A7A77BB7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16804 pso_num=25 pso_serial#=122 2025-06-21 12:03:06.627942 :A7A77BB8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16804 osp_idx=23 osp_ver=163689 osp_pg=0 (spawn #307779) 2025-06-21 12:03:06.627942 :A7A77BB9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 12:03:06.627943 :A7A77BBA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=177 time=3483129813 2025-06-21 12:03:06.627943 :A7A77BBB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 12:03:06.627944 :A7A77BBC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 12:03:06.627944 :A7A77BBD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 12:03:06.627944 :A7A77BBE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 12:03:06.627944 :A7A77BBF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 12:03:06.628455 :A7A77BC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 12:03:06.628500 :A7A77BC8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 12:13:07.527807 :A7A8454C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 12:13:07.886547 :A7A845B4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 12:13:07.881 2025-06-21 12:13:07.886548 :A7A845B5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17812 pso_num=25 pso_serial#=123 2025-06-21 12:13:07.886688 :A7A845B6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17812 osp_idx=23 osp_ver=163690 osp_pg=0 (spawn #307802) 2025-06-21 12:13:07.886688 :A7A845B7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 12:13:07.886689 :A7A845B8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=178 time=3483731079 2025-06-21 12:13:07.886689 :A7A845B9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 12:13:07.886689 :A7A845BA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 12:13:07.886690 :A7A845BB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 12:13:07.886690 :A7A845BC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 12:13:07.886690 :A7A845BD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 12:13:07.887177 :A7A845BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 12:13:07.887226 :A7A845C6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 12:23:08.853327 :A7A90B4C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 12:23:09.154604 :A7A90BA6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 12:23:09.150 2025-06-21 12:23:09.154606 :A7A90BA7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18168 pso_num=25 pso_serial#=124 2025-06-21 12:23:09.154741 :A7A90BA8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18168 osp_idx=23 osp_ver=163691 osp_pg=0 (spawn #307825) 2025-06-21 12:23:09.154742 :A7A90BA9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 12:23:09.154742 :A7A90BAA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=179 time=3484332344 2025-06-21 12:23:09.154742 :A7A90BAB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 12:23:09.154743 :A7A90BAC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 12:23:09.154743 :A7A90BAD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 12:23:09.154743 :A7A90BAE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 12:23:09.154744 :A7A90BAF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 12:23:09.155216 :A7A90BB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 12:23:09.155261 :A7A90BB8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 12:33:10.135291 :A7A9CD23:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 12:33:10.444725 :A7A9CD8F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 12:33:10.439 2025-06-21 12:33:10.444728 :A7A9CD90:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16276 pso_num=25 pso_serial#=125 2025-06-21 12:33:10.444865 :A7A9CD91:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16276 osp_idx=23 osp_ver=163692 osp_pg=0 (spawn #307847) 2025-06-21 12:33:10.444866 :A7A9CD92:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 12:33:10.444866 :A7A9CD93:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=180 time=3484933641 2025-06-21 12:33:10.444867 :A7A9CD94:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 12:33:10.444867 :A7A9CD95:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 12:33:10.444868 :A7A9CD96:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 12:33:10.444868 :A7A9CD97:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 12:33:10.444868 :A7A9CD98:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 12:33:10.445354 :A7A9CD99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 12:33:10.445397 :A7A9CDA1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 12:35:43.008663 :A7A9FDC5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 12:43:11.674614 :A7AA8FD2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-21 12:53:12.494324 :A7AB5254:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 12:53:12.904885 :A7AB52B3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 12:53:12.900 2025-06-21 12:53:12.904887 :A7AB52B4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16880 pso_num=25 pso_serial#=126 2025-06-21 12:53:12.905027 :A7AB52B5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16880 osp_idx=23 osp_ver=163693 osp_pg=0 (spawn #307893) 2025-06-21 12:53:12.905027 :A7AB52B6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 12:53:12.905028 :A7AB52B7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=181 time=3486136094 2025-06-21 12:53:12.905028 :A7AB52B8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 12:53:12.905029 :A7AB52B9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 12:53:12.905029 :A7AB52BA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 12:53:12.905029 :A7AB52BB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 12:53:12.905030 :A7AB52BC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 12:53:12.905528 :A7AB52BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 12:53:12.905574 :A7AB52C5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 13:03:13.742383 :A7AC1897:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 13:03:14.200134 :A7AC18FF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 13:03:14.195 2025-06-21 13:03:14.200137 :A7AC1900:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12380 pso_num=25 pso_serial#=127 2025-06-21 13:03:14.200282 :A7AC1901:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12380 osp_idx=23 osp_ver=163694 osp_pg=0 (spawn #307918) 2025-06-21 13:03:14.200282 :A7AC1902:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 13:03:14.200283 :A7AC1903:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=182 time=3486737391 2025-06-21 13:03:14.200283 :A7AC1904:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 13:03:14.200284 :A7AC1905:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 13:03:14.200284 :A7AC1906:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 13:03:14.200284 :A7AC1907:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 13:03:14.200285 :A7AC1908:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 13:03:14.200770 :A7AC1909:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 13:03:14.200813 :A7AC1911:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 13:13:15.176103 :A7ACE041:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 13:13:15.539867 :A7ACE09E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 13:13:15.535 2025-06-21 13:13:15.539870 :A7ACE09F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18424 pso_num=25 pso_serial#=128 2025-06-21 13:13:15.540004 :A7ACE0A0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18424 osp_idx=23 osp_ver=163695 osp_pg=0 (spawn #307941) 2025-06-21 13:13:15.540005 :A7ACE0A1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 13:13:15.540006 :A7ACE0A2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=183 time=3487338735 2025-06-21 13:13:15.540006 :A7ACE0A3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 13:13:15.540006 :A7ACE0A4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 13:13:15.540007 :A7ACE0A5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 13:13:15.540007 :A7ACE0A6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 13:13:15.540007 :A7ACE0A7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 13:13:15.540503 :A7ACE0A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 13:13:15.540553 :A7ACE0AF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 13:23:16.431618 :A7ADA548:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 13:23:16.733318 :A7ADA5AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 13:23:16.728 2025-06-21 13:23:16.733320 :A7ADA5AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14260 pso_num=25 pso_serial#=129 2025-06-21 13:23:16.733460 :A7ADA5AF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14260 osp_idx=23 osp_ver=163696 osp_pg=0 (spawn #307964) 2025-06-21 13:23:16.733461 :A7ADA5B0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 13:23:16.733461 :A7ADA5B1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=184 time=3487939922 2025-06-21 13:23:16.733461 :A7ADA5B2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 13:23:16.733462 :A7ADA5B3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 13:23:16.733462 :A7ADA5B4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 13:23:16.733462 :A7ADA5B5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 13:23:16.733463 :A7ADA5B6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 13:23:16.733969 :A7ADA5B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 13:23:16.734012 :A7ADA5BF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 13:33:17.533488 :A7AE679C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 13:33:18.195404 :A7AE67FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 13:33:18.190 2025-06-21 13:33:18.195407 :A7AE67FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16940 pso_num=25 pso_serial#=130 2025-06-21 13:33:18.195554 :A7AE67FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16940 osp_idx=23 osp_ver=163697 osp_pg=0 (spawn #307986) 2025-06-21 13:33:18.195554 :A7AE67FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 13:33:18.195555 :A7AE67FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=185 time=3488541391 2025-06-21 13:33:18.195555 :A7AE67FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 13:33:18.195556 :A7AE6800:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 13:33:18.195556 :A7AE6801:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 13:33:18.195556 :A7AE6802:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 13:33:18.195556 :A7AE6803:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 13:33:18.196064 :A7AE6804:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 13:33:18.196113 :A7AE680D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 13:43:19.077077 :A7AF2A5D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 13:43:19.487736 :A7AF2AC8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 13:43:19.483 2025-06-21 13:43:19.487738 :A7AF2AC9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17900 pso_num=25 pso_serial#=131 2025-06-21 13:43:19.487876 :A7AF2ACA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17900 osp_idx=23 osp_ver=163698 osp_pg=0 (spawn #308009) 2025-06-21 13:43:19.487876 :A7AF2ACB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 13:43:19.487877 :A7AF2ACC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=186 time=3489142672 2025-06-21 13:43:19.487877 :A7AF2ACD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 13:43:19.487878 :A7AF2ACE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 13:43:19.487878 :A7AF2ACF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 13:43:19.487879 :A7AF2AD0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 13:43:19.487879 :A7AF2AD1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 13:43:19.488396 :A7AF2AD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 13:43:19.488439 :A7AF2ADA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 13:53:20.322654 :A7AFED77:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 13:53:20.904802 :A7AFEDD3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 13:53:20.900 2025-06-21 13:53:20.904803 :A7AFEDD4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15708 pso_num=25 pso_serial#=132 2025-06-21 13:53:20.904935 :A7AFEDD5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15708 osp_idx=23 osp_ver=163699 osp_pg=0 (spawn #308032) 2025-06-21 13:53:20.904935 :A7AFEDD6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 13:53:20.904936 :A7AFEDD7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=187 time=3489744094 2025-06-21 13:53:20.904936 :A7AFEDD8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 13:53:20.904936 :A7AFEDD9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 13:53:20.904937 :A7AFEDDA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 13:53:20.904937 :A7AFEDDB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 13:53:20.904938 :A7AFEDDC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 13:53:20.905439 :A7AFEDDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 13:53:20.905484 :A7AFEDE6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-21 14:03:21.876550 :A7B0B1ED:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-21 14:03:22.334744 :A7B0B270:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 14:03:22.329 2025-06-21 14:03:22.334746 :A7B0B271:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16092 pso_num=25 pso_serial#=133 2025-06-21 14:03:22.334881 :A7B0B272:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16092 osp_idx=23 osp_ver=163700 osp_pg=0 (spawn #308057) 2025-06-21 14:03:22.334881 :A7B0B273:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-21 14:03:22.334882 :A7B0B274:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=188 time=3490345532 2025-06-21 14:03:22.334882 :A7B0B275:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-21 14:03:22.334883 :A7B0B276:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-21 14:03:22.334883 :A7B0B277:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-21 14:03:22.334883 :A7B0B278:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-21 14:03:22.334884 :A7B0B279:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-21 14:03:22.335424 :A7B0B27A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-21 14:03:22.335469 :A7B0B281:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 16092, W000)