Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719100212\orcl_w000_15496_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:36217M/63366M, Ph+PgF:42169M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15496, image: ORACLE.EXE (W000) *** 2025-07-19 10:02:12.928 *** SESSION ID:(177.15515) 2025-07-19 10:02:12.928 *** 2025-07-19 10:02:12.928 Process diagnostic dump for ORACLE.EXE (W000), OS id=15496, pid: 22, proc_ser: 93, sid: 177, sess_ser: 15515 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=3.948263 sec, exc=3.948263 sec, total=3.948263 sec wait times: max=5.000000 sec, heur=2 min 54 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.000026 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.014729 sec, exc=5.014729 sec, total=5.014729 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: 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.015263 sec, exc=5.015263 sec, total=5.015263 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: 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.003217 sec, exc=5.003217 sec, total=5.003217 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=31 seq_num=32 snap_id=1 wait times: snap=5.013019 sec, exc=5.013019 sec, total=5.013019 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 4: 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.004079 sec, exc=5.004079 sec, total=5.004079 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: 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.010143 sec, exc=5.010143 sec, total=5.010143 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 6: 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.014719 sec, exc=5.014719 sec, total=5.014719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: 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.004626 sec, exc=5.004626 sec, total=5.004626 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=26 seq_num=27 snap_id=1 wait times: snap=5.008325 sec, exc=5.008325 sec, total=5.008325 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 9: 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.000300 sec, exc=5.000300 sec, total=5.000300 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 177 serial 15515 --------------------------------------------------- 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, 10:00:12 - 10:02:12] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-19 10:02:12.928 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15496, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-19 07:38:59.586283 :B402DA45:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 07:38:59.586284 :B402DA46:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 07:38:59.586807 :B402DA47: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-07-19 07:38:59.586852 :B402DA4F: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-07-19 07:49:00.456481 :B403A63B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 07:49:00.945601 :B403A662:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 07:49:00.941 2025-07-19 07:49:00.945603 :B403A663:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10784 pso_num=22 pso_serial#=79 2025-07-19 07:49:00.945749 :B403A664:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10784 osp_idx=0 osp_ver=419881 osp_pg=0 (spawn #400530) 2025-07-19 07:49:00.945750 :B403A665:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 07:49:00.945750 :B403A666:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=191 time=1592116845 2025-07-19 07:49:00.945750 :B403A667:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 07:49:00.945751 :B403A668:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 07:49:00.945751 :B403A669:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 07:49:00.945751 :B403A66A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 07:49:00.945752 :B403A66B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 07:49:00.946254 :B403A66C: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-07-19 07:49:00.946313 :B403A673: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-07-19 07:59:01.844184 :B40471A2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 07:59:02.380238 :B40471C9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 07:59:02.375 2025-07-19 07:59:02.380241 :B40471CA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12540 pso_num=22 pso_serial#=80 2025-07-19 07:59:02.380380 :B40471CB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12540 osp_idx=0 osp_ver=419882 osp_pg=0 (spawn #400552) 2025-07-19 07:59:02.380381 :B40471CC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 07:59:02.380381 :B40471CD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=192 time=1592718267 2025-07-19 07:59:02.380382 :B40471CE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 07:59:02.380382 :B40471CF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 07:59:02.380383 :B40471D0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 07:59:02.380383 :B40471D1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 07:59:02.380383 :B40471D2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 07:59:02.380879 :B40471D3: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-07-19 07:59:02.380924 :B40471DB: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-07-19 08:09:03.320396 :B40548D4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:09:03.698526 :B40548D7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:09:03.695 2025-07-19 08:09:03.698528 :B40548D8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18824 pso_num=22 pso_serial#=81 2025-07-19 08:09:03.698662 :B40548D9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18824 (legacy spawn) 2025-07-19 08:09:03.705158 :B40548DA: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-07-19 08:09:03.705333 :B40548DF: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-07-19 08:09:03.706170 :B40548E4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:09:03.714862 :B40548E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:09:03.710 2025-07-19 08:09:03.714864 :B40548E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10676 pso_num=22 pso_serial#=82 2025-07-19 08:09:03.715001 :B40548E9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10676 (legacy spawn) 2025-07-19 08:09:03.721409 :B40548EA: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-07-19 08:09:03.721572 :B40548F3: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-07-19 08:09:03.722359 :B40548F4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:09:03.778064 :B405490F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:09:03.773 2025-07-19 08:09:03.778066 :B4054910:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19216 pso_num=22 pso_serial#=83 2025-07-19 08:09:03.778208 :B4054911:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19216 osp_idx=0 osp_ver=419885 osp_pg=0 (spawn #400578) 2025-07-19 08:09:03.778209 :B4054912:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 08:09:03.778209 :B4054913:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=193 time=1593319673 2025-07-19 08:09:03.778210 :B4054914:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 08:09:03.778210 :B4054915:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 08:09:03.778211 :B4054916:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 08:09:03.778211 :B4054917:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 08:09:03.778211 :B4054918:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 08:09:03.778707 :B4054919: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-07-19 08:09:03.778750 :B4054920: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-07-19 08:19:04.714369 :B4066363:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:19:05.156638 :B4066389:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:19:05.152 2025-07-19 08:19:05.156641 :B406638A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14056 pso_num=22 pso_serial#=84 2025-07-19 08:19:05.156784 :B406638B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14056 osp_idx=0 osp_ver=419886 osp_pg=0 (spawn #400606) 2025-07-19 08:19:05.156784 :B406638C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 08:19:05.156785 :B406638D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=194 time=1593921048 2025-07-19 08:19:05.156785 :B406638E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 08:19:05.156785 :B406638F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 08:19:05.156786 :B4066390:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 08:19:05.156786 :B4066391:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 08:19:05.156786 :B4066392:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 08:19:05.157278 :B4066393: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-07-19 08:19:05.157326 :B406639D: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-07-19 08:29:06.078313 :B4074D9C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:29:06.551886 :B4074DC3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:29:06.547 2025-07-19 08:29:06.551888 :B4074DC4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19048 pso_num=22 pso_serial#=85 2025-07-19 08:29:06.552028 :B4074DC5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19048 osp_idx=0 osp_ver=419887 osp_pg=0 (spawn #400628) 2025-07-19 08:29:06.552029 :B4074DC6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 08:29:06.552029 :B4074DC7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=1594522439 2025-07-19 08:29:06.552029 :B4074DC8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 08:29:06.552029 :B4074DC9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 08:29:06.552030 :B4074DCA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 08:29:06.552030 :B4074DCB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 08:29:06.552031 :B4074DCC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 08:29:06.552521 :B4074DCD: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-07-19 08:29:06.552575 :B4074DD3: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-07-19 08:37:46.829023 :B407FEC4: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-07-19 08:39:07.919512 :B4081D05: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-07-19 08:49:08.859549 :B408F037:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:49:09.317476 :B408F051:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:49:09.313 2025-07-19 08:49:09.317478 :B408F052:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18868 pso_num=22 pso_serial#=86 2025-07-19 08:49:09.317617 :B408F053:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18868 osp_idx=0 osp_ver=419888 osp_pg=0 (spawn #400674) 2025-07-19 08:49:09.317618 :B408F054:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 08:49:09.317618 :B408F055:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=196 time=1595725204 2025-07-19 08:49:09.317618 :B408F056:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 08:49:09.317619 :B408F057:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 08:49:09.317619 :B408F058:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 08:49:09.317619 :B408F059:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 08:49:09.317620 :B408F05A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 08:49:09.318126 :B408F05B: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-07-19 08:49:09.318179 :B408F062: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-07-19 08:59:10.213450 :B409BBE5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 08:59:10.687061 :B409BC0B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 08:59:10.682 2025-07-19 08:59:10.687062 :B409BC0C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19396 pso_num=22 pso_serial#=87 2025-07-19 08:59:10.687200 :B409BC0D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19396 osp_idx=0 osp_ver=419889 osp_pg=0 (spawn #400696) 2025-07-19 08:59:10.687201 :B409BC0E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 08:59:10.687201 :B409BC0F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=1596326579 2025-07-19 08:59:10.687202 :B409BC10:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 08:59:10.687202 :B409BC11:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 08:59:10.687203 :B409BC12:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 08:59:10.687203 :B409BC13:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 08:59:10.687204 :B409BC14:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 08:59:10.687704 :B409BC15: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-07-19 08:59:10.687748 :B409BC1D: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-07-19 09:09:11.664650 :B40A916F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 09:09:12.037656 :B40A9189:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 09:09:12.032 2025-07-19 09:09:12.037659 :B40A918A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18608 pso_num=22 pso_serial#=88 2025-07-19 09:09:12.037801 :B40A918B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18608 osp_idx=0 osp_ver=419890 osp_pg=0 (spawn #400722) 2025-07-19 09:09:12.037802 :B40A918C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 09:09:12.037802 :B40A918D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=198 time=1596927939 2025-07-19 09:09:12.037803 :B40A918E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 09:09:12.037803 :B40A918F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 09:09:12.037804 :B40A9190:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 09:09:12.037804 :B40A9191:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 09:09:12.037804 :B40A9192:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 09:09:12.038310 :B40A9193: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-07-19 09:09:12.038361 :B40A919D: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-07-19 09:19:13.079462 :B40B607D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 09:19:13.396906 :B40B60A3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 09:19:13.391 2025-07-19 09:19:13.396908 :B40B60A4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12004 pso_num=22 pso_serial#=89 2025-07-19 09:19:13.397048 :B40B60A5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12004 osp_idx=0 osp_ver=419891 osp_pg=0 (spawn #400745) 2025-07-19 09:19:13.397048 :B40B60A6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 09:19:13.397049 :B40B60A7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=199 time=1597529298 2025-07-19 09:19:13.397049 :B40B60A8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 09:19:13.397050 :B40B60A9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 09:19:13.397050 :B40B60AA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 09:19:13.397051 :B40B60AB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 09:19:13.397051 :B40B60AC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 09:19:13.397541 :B40B60AD: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-07-19 09:19:13.397589 :B40B60B7: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-07-19 09:29:14.297729 :B40C2BC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 09:29:14.599208 :B40C2BDB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 09:29:14.594 2025-07-19 09:29:14.599210 :B40C2BDC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18832 pso_num=22 pso_serial#=90 2025-07-19 09:29:14.599345 :B40C2BDD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18832 osp_idx=0 osp_ver=419892 osp_pg=0 (spawn #400767) 2025-07-19 09:29:14.599345 :B40C2BDE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 09:29:14.599346 :B40C2BDF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=200 time=1598130501 2025-07-19 09:29:14.599346 :B40C2BE0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 09:29:14.599347 :B40C2BE1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 09:29:14.599347 :B40C2BE2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 09:29:14.599347 :B40C2BE3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 09:29:14.599348 :B40C2BE4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 09:29:14.599833 :B40C2BE5: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-07-19 09:29:14.599876 :B40C2BED: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-07-19 09:39:15.590832 :B40CFEE6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 09:39:16.033168 :B40CFF00:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 09:39:16.028 2025-07-19 09:39:16.033170 :B40CFF01:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18844 pso_num=22 pso_serial#=91 2025-07-19 09:39:16.033306 :B40CFF02:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18844 osp_idx=0 osp_ver=419893 osp_pg=0 (spawn #400790) 2025-07-19 09:39:16.033307 :B40CFF03:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 09:39:16.033307 :B40CFF04:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=201 time=1598731923 2025-07-19 09:39:16.033307 :B40CFF05:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 09:39:16.033308 :B40CFF06:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 09:39:16.033308 :B40CFF07:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 09:39:16.033309 :B40CFF08:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 09:39:16.033309 :B40CFF09:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 09:39:16.033807 :B40CFF0A: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-07-19 09:39:16.033851 :B40CFF14: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-07-19 09:49:17.045953 :B40DCAC9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 09:49:17.363204 :B40DCAE3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 09:49:17.358 2025-07-19 09:49:17.363206 :B40DCAE4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18612 pso_num=22 pso_serial#=92 2025-07-19 09:49:17.363346 :B40DCAE5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18612 osp_idx=0 osp_ver=419894 osp_pg=0 (spawn #400813) 2025-07-19 09:49:17.363347 :B40DCAE6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 09:49:17.363347 :B40DCAE7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=202 time=1599333251 2025-07-19 09:49:17.363348 :B40DCAE8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 09:49:17.363348 :B40DCAE9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 09:49:17.363348 :B40DCAEA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 09:49:17.363348 :B40DCAEB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 09:49:17.363349 :B40DCAEC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 09:49:17.363851 :B40DCAED: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-07-19 09:49:17.363897 :B40DCAF4: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-07-19 09:59:18.261067 :B40E9658:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 09:59:18.703332 :B40E969B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 09:59:18.698 2025-07-19 09:59:18.703336 :B40E969C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15496 pso_num=22 pso_serial#=93 2025-07-19 09:59:18.703467 :B40E969D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15496 osp_idx=0 osp_ver=419895 osp_pg=0 (spawn #400835) 2025-07-19 09:59:18.703468 :B40E969E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 09:59:18.703468 :B40E969F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=203 time=1599934595 2025-07-19 09:59:18.703469 :B40E96A0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 09:59:18.703469 :B40E96A1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 09:59:18.703469 :B40E96A2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 09:59:18.703469 :B40E96A3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 09:59:18.703470 :B40E96A4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 09:59:18.703948 :B40E96A5: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-07-19 09:59:18.703991 :B40E96AD: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: 15496, W000)