Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622221013\orcl_w000_14788_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:36373M/63366M, Ph+PgF:42654M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14788, image: ORACLE.EXE (W000) *** 2025-06-22 22:10:13.478 *** SESSION ID:(201.41065) 2025-06-22 22:10:13.478 *** 2025-06-22 22:10:13.478 Process diagnostic dump for ORACLE.EXE (W000), OS id=14788, pid: 25, proc_ser: 107, sid: 201, sess_ser: 41065 ------------------------------------------------------------------------------- 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=3.430512 sec, exc=3.430512 sec, total=3.430512 sec wait times: max=5.000000 sec, heur=2 min 48 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.000021 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.010156 sec, exc=5.010156 sec, total=5.010156 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 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.014733 sec, exc=5.014733 sec, total=5.014733 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 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.015247 sec, exc=5.015247 sec, total=5.015247 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 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.003623 sec, exc=5.003623 sec, total=5.003623 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=29 seq_num=30 snap_id=1 wait times: snap=5.001135 sec, exc=5.001135 sec, total=5.001135 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.014723 sec, exc=5.014723 sec, total=5.014723 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 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.010135 sec, exc=5.010135 sec, total=5.010135 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=26 seq_num=27 snap_id=1 wait times: snap=5.004224 sec, exc=5.004224 sec, total=5.004224 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 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.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.000031 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.000363 sec, exc=5.000363 sec, total=5.000363 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time Sampled Session History of session 201 serial 41065 --------------------------------------------------- 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, 22:08:13 - 22:10:13] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-22 22:10:13.478 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14788, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-22 19:47:06.677764 :A83CDD78:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13044 pso_num=25 pso_serial#=93 2025-06-22 19:47:06.677898 :A83CDD79:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13044 (legacy spawn) 2025-06-22 19:47:06.684585 :A83CDD7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-22 19:47:06.684738 :A83CDD7F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-22 19:47:06.685696 :A83CDD84:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 19:47:06.897359 :A83CDDDF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 19:47:06.892 2025-06-22 19:47:06.897362 :A83CDDE0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16984 pso_num=25 pso_serial#=94 2025-06-22 19:47:06.897500 :A83CDDE1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16984 osp_idx=23 osp_ver=165956 osp_pg=0 (spawn #312198) 2025-06-22 19:47:06.897500 :A83CDDE2: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-22 19:47:06.897501 :A83CDDE3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=118 time=3597370094 2025-06-22 19:47:06.897501 :A83CDDE4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 19:47:06.897502 :A83CDDE5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 19:47:06.897503 :A83CDDE6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 19:47:06.897503 :A83CDDE7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 19:47:06.897503 :A83CDDE8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 19:47:06.897990 :A83CDDE9: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-22 19:47:06.898037 :A83CDDEF: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-22 19:57:07.722790 :A83DA4C3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 19:57:08.211867 :A83DA513:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 19:57:08.206 2025-06-22 19:57:08.211869 :A83DA514:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16932 pso_num=25 pso_serial#=95 2025-06-22 19:57:08.212003 :A83DA515:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16932 osp_idx=23 osp_ver=165957 osp_pg=0 (spawn #312221) 2025-06-22 19:57:08.212004 :A83DA516: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-22 19:57:08.212005 :A83DA517:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=119 time=3597971407 2025-06-22 19:57:08.212005 :A83DA518:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 19:57:08.212006 :A83DA519:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 19:57:08.212006 :A83DA51A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 19:57:08.212006 :A83DA51B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 19:57:08.212007 :A83DA51C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 19:57:08.212515 :A83DA51D: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-22 19:57:08.212559 :A83DA524: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-22 20:07:09.097007 :A83E748E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 20:07:09.570726 :A83E74D2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 20:07:09.565 2025-06-22 20:07:09.570729 :A83E74D3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16772 pso_num=25 pso_serial#=96 2025-06-22 20:07:09.570872 :A83E74D4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16772 osp_idx=23 osp_ver=165958 osp_pg=0 (spawn #312246) 2025-06-22 20:07:09.570872 :A83E74D5: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-22 20:07:09.570873 :A83E74D6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=120 time=3598572766 2025-06-22 20:07:09.570873 :A83E74D7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 20:07:09.570874 :A83E74D8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 20:07:09.570874 :A83E74D9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 20:07:09.570874 :A83E74DA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 20:07:09.570875 :A83E74DB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 20:07:09.571418 :A83E74DC: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-22 20:07:09.571466 :A83E74E4: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-22 20:17:10.433554 :A83F3E29:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 20:17:10.786563 :A83F3E66:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 20:17:10.782 2025-06-22 20:17:10.786565 :A83F3E67:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17080 pso_num=25 pso_serial#=97 2025-06-22 20:17:10.786707 :A83F3E68:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17080 osp_idx=23 osp_ver=165959 osp_pg=0 (spawn #312269) 2025-06-22 20:17:10.786708 :A83F3E69: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-22 20:17:10.786708 :A83F3E6A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=121 time=3599173969 2025-06-22 20:17:10.786708 :A83F3E6B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 20:17:10.786709 :A83F3E6C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 20:17:10.786709 :A83F3E6D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 20:17:10.786710 :A83F3E6E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 20:17:10.786710 :A83F3E6F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 20:17:10.787202 :A83F3E70: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-22 20:17:10.787251 :A83F3E77: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-22 20:27:11.558378 :A84004BD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 20:27:12.125641 :A84004F3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 20:27:12.121 2025-06-22 20:27:12.125644 :A84004F4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16740 pso_num=25 pso_serial#=98 2025-06-22 20:27:12.125781 :A84004F5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16740 osp_idx=23 osp_ver=165960 osp_pg=0 (spawn #312292) 2025-06-22 20:27:12.125781 :A84004F6: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-22 20:27:12.125782 :A84004F7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=3599775313 2025-06-22 20:27:12.125782 :A84004F8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 20:27:12.125783 :A84004F9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 20:27:12.125783 :A84004FA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 20:27:12.125783 :A84004FB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 20:27:12.125784 :A84004FC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 20:27:12.126293 :A84004FD: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-22 20:27:12.126336 :A8400504: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-22 20:37:12.962345 :A840CC35:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 20:37:13.435780 :A840CC7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 20:37:13.431 2025-06-22 20:37:13.435782 :A840CC7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18188 pso_num=25 pso_serial#=99 2025-06-22 20:37:13.435915 :A840CC7D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18188 osp_idx=23 osp_ver=165961 osp_pg=0 (spawn #312314) 2025-06-22 20:37:13.435916 :A840CC7E: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-22 20:37:13.435916 :A840CC7F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=3600376625 2025-06-22 20:37:13.435917 :A840CC80:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 20:37:13.435917 :A840CC81:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 20:37:13.435918 :A840CC82:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 20:37:13.435918 :A840CC83:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 20:37:13.435918 :A840CC84:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 20:37:13.436421 :A840CC85: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-22 20:37:13.436464 :A840CC8D: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-22 20:47:14.205799 :A84192C8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 20:47:14.710540 :A841930F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 20:47:14.705 2025-06-22 20:47:14.710542 :A8419310:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18068 pso_num=25 pso_serial#=100 2025-06-22 20:47:14.710679 :A8419311:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18068 osp_idx=23 osp_ver=165962 osp_pg=0 (spawn #312337) 2025-06-22 20:47:14.710681 :A8419312: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-22 20:47:14.710681 :A8419313:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=3600977907 2025-06-22 20:47:14.710681 :A8419314:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 20:47:14.710682 :A8419315:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 20:47:14.710682 :A8419316:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 20:47:14.710683 :A8419317:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 20:47:14.710683 :A8419318:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 20:47:14.711202 :A8419319: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-22 20:47:14.711262 :A8419322: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-22 20:57:15.459191 :A84259AD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 20:57:15.885944 :A84259EC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 20:57:15.881 2025-06-22 20:57:15.885947 :A84259ED:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15628 pso_num=25 pso_serial#=101 2025-06-22 20:57:15.886096 :A84259EE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15628 osp_idx=23 osp_ver=165963 osp_pg=0 (spawn #312360) 2025-06-22 20:57:15.886097 :A84259EF: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-22 20:57:15.886098 :A84259F0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=3601579079 2025-06-22 20:57:15.886098 :A84259F1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 20:57:15.886099 :A84259F2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 20:57:15.886099 :A84259F3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 20:57:15.886099 :A84259F4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 20:57:15.886100 :A84259F5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 20:57:15.886624 :A84259F6: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-22 20:57:15.886668 :A84259FD: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-22 21:07:16.743361 :A8432960:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 21:07:17.060778 :A8432997:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 21:07:17.056 2025-06-22 21:07:17.060780 :A8432998:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10948 pso_num=25 pso_serial#=102 2025-06-22 21:07:17.060917 :A8432999:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10948 osp_idx=23 osp_ver=165964 osp_pg=0 (spawn #312385) 2025-06-22 21:07:17.060918 :A843299A: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-22 21:07:17.060918 :A843299B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=3602180250 2025-06-22 21:07:17.060918 :A843299C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 21:07:17.060919 :A843299D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 21:07:17.060920 :A843299E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 21:07:17.060920 :A843299F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 21:07:17.060922 :A84329A0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 21:07:17.061474 :A84329A1: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-22 21:07:17.061524 :A84329A7: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-22 21:16:40.906780 :A843E28C: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-22 21:17:18.411379 :A843F292: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-22 21:27:19.322534 :A844B533:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 21:27:19.754057 :A844B595:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 21:27:19.749 2025-06-22 21:27:19.754059 :A844B596:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16256 pso_num=25 pso_serial#=103 2025-06-22 21:27:19.754196 :A844B597:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16256 osp_idx=23 osp_ver=165965 osp_pg=0 (spawn #312430) 2025-06-22 21:27:19.754197 :A844B598: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-22 21:27:19.754197 :A844B599:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=3603382938 2025-06-22 21:27:19.754197 :A844B59A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 21:27:19.754198 :A844B59B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 21:27:19.754198 :A844B59C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 21:27:19.754199 :A844B59D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 21:27:19.754199 :A844B59E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 21:27:19.754677 :A844B59F: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-22 21:27:19.754721 :A844B5A6: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-22 21:37:20.595831 :A84577D6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 21:37:21.022345 :A8457837:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 21:37:21.017 2025-06-22 21:37:21.022347 :A8457838:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3236 pso_num=25 pso_serial#=104 2025-06-22 21:37:21.022481 :A8457839:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3236 osp_idx=23 osp_ver=165966 osp_pg=0 (spawn #312452) 2025-06-22 21:37:21.022482 :A845783A: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-22 21:37:21.022482 :A845783B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=128 time=3603984219 2025-06-22 21:37:21.022482 :A845783C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 21:37:21.022483 :A845783D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 21:37:21.022483 :A845783E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 21:37:21.022483 :A845783F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 21:37:21.022484 :A8457840:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 21:37:21.022961 :A8457841: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-22 21:37:21.023006 :A845784A: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-22 21:47:21.983867 :A84639C1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 21:47:22.223014 :A84639FB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 21:47:22.218 2025-06-22 21:47:22.223016 :A84639FC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3836 pso_num=25 pso_serial#=105 2025-06-22 21:47:22.223152 :A84639FD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3836 osp_idx=23 osp_ver=165967 osp_pg=0 (spawn #312475) 2025-06-22 21:47:22.223153 :A84639FE: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-22 21:47:22.223153 :A84639FF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=129 time=3604585407 2025-06-22 21:47:22.223154 :A8463A00:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 21:47:22.223154 :A8463A01:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 21:47:22.223155 :A8463A02:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 21:47:22.223155 :A8463A03:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 21:47:22.223155 :A8463A04:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 21:47:22.223643 :A8463A05: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-22 21:47:22.223692 :A8463A0D: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-22 21:57:23.258415 :A846FD5C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 21:57:23.450523 :A846FD9C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 21:57:23.446 2025-06-22 21:57:23.450525 :A846FD9D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18320 pso_num=25 pso_serial#=106 2025-06-22 21:57:23.450669 :A846FD9E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18320 osp_idx=23 osp_ver=165968 osp_pg=0 (spawn #312498) 2025-06-22 21:57:23.450670 :A846FD9F: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-22 21:57:23.450670 :A846FDA0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=3605186641 2025-06-22 21:57:23.450671 :A846FDA1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 21:57:23.450671 :A846FDA2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 21:57:23.450672 :A846FDA3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 21:57:23.450672 :A846FDA4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 21:57:23.450672 :A846FDA5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 21:57:23.451173 :A846FDA6: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-22 21:57:23.451220 :A846FDAE: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-22 22:07:24.414584 :A847C7E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-22 22:07:24.778828 :A847C82C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-22 22:07:24.779 2025-06-22 22:07:24.778830 :A847C82D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14788 pso_num=25 pso_serial#=107 2025-06-22 22:07:24.778973 :A847C82E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14788 osp_idx=23 osp_ver=165969 osp_pg=0 (spawn #312523) 2025-06-22 22:07:24.778974 :A847C82F: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-22 22:07:24.778975 :A847C830:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=3605787969 2025-06-22 22:07:24.778975 :A847C831:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-22 22:07:24.778975 :A847C832:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-22 22:07:24.778976 :A847C833:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-22 22:07:24.778976 :A847C834:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-22 22:07:24.778976 :A847C835:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-22 22:07:24.779498 :A847C836: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-22 22:07:24.779545 :A847C83C: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: 14788, W000)